recv 1221 bytes from udp/[172.16.254.191]:58573 at 14:40:16.144516: ------------------------------------------------------------------------ INVITE sip:84810@pci.vivint.com:5080 SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372 From: ;tag=96CADFAC-220E To: Date: Thu, 10 Nov 2016 21:40:16 GMT Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 Supported: timer,resource-priority,replaces,histinfo,sdp-anat Require: 100rel Min-SE: 1800 Cisco-Guid: 0441279221-2797933030-2467801767-0541322053 User-Agent: Cisco-SIPGateway/IOS-15.3.3.M5 Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Timestamp: 1478814016 Contact: History-Info: ;index=1 Expires: 180 Allow-Events: telephone-event Max-Forwards: 53 P-Asserted-Identity: Session-Expires: 1800 Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 253 v=0 o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191 s=SIP Call c=IN IP4 172.16.254.191 t=0 0 m=audio 20606 RTP/AVP 0 120 c=IN IP4 172.16.254.191 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 ------------------------------------------------------------------------ send 368 bytes to udp/[172.16.254.191]:5060 at 14:40:16.145073: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191 From: ;tag=96CADFAC-220E To: Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 101 INVITE Timestamp: 1478814016 0.000159 User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:16.143933 [NOTICE] switch_channel.c:1104 New Channel sofia/external/8017189923@apex.local [44b78e98-a78e-11e6-b7e1-85d38162b0e3] 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_NEW 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:9725 sofia/external/8017189923@apex.local receiving invite from 172.16.254.191:58573 version: 1.6.12 64bit 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [received][100] 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7024 Remote SDP: v=0 o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191 s=SIP Call c=IN IP4 172.16.254.191 t=0 0 m=audio 20606 RTP/AVP 0 120 c=IN IP4 172.16.254.191 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7378 (sofia/external/8017189923@apex.local) State Change CS_NEW -> CS_INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:603 (sofia/external/8017189923@apex.local) State NEW 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:90 sofia/external/8017189923@apex.local SOFIA INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:40 sofia/external/8017189923@apex.local Standard INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:48 (sofia/external/8017189923@apex.local) State Change CS_INIT -> CS_ROUTING 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT going to sleep 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_ROUTING 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:2249 (sofia/external/8017189923@apex.local) Callstate Change DOWN -> RINGING 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:143 sofia/external/8017189923@apex.local SOFIA ROUTING 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:236 sofia/external/8017189923@apex.local Standard ROUTING 2016-11-10 14:40:16.143933 [INFO] mod_dialplan_xml.c:637 Processing 8017189923 <8017189923>->84810 in context vivint Dialplan: sofia/external/8017189923@apex.local parsing [vivint->unloop] continue=false Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/external/8017189923@apex.local parsing [vivint->outside_call] continue=true Dialplan: sofia/external/8017189923@apex.local Absolute Condition [outside_call] Dialplan: sofia/external/8017189923@apex.local Action set(outside_call=true) Dialplan: sofia/external/8017189923@apex.local Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/8017189923@apex.local parsing [vivint->call_debug] continue=true Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/8017189923@apex.local parsing [vivint->test] continue=false Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [test] chan_name(sofia/external/8017189923@apex.local) =~ /^sofia\/external/ break=on-false Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [test] destination_number(84810) =~ /^5551212$/ break=on-false Dialplan: sofia/external/8017189923@apex.local parsing [vivint->default] continue=false Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [default] chan_name(sofia/external/8017189923@apex.local) =~ /^sofia\/external/ break=on-false Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [default] destination_number(84810) =~ /^.....$/ break=on-false Dialplan: sofia/external/8017189923@apex.local Action set(domain_name=freeswitch1-stg.pci.vivint.com) Dialplan: sofia/external/8017189923@apex.local Action bridge(sofia/external/${destination_number}@apex.local;fs_path=sip:172.16.254.122|sofia/external/${destination_number}@apex.local;fs_path=sip:172.16.254.125) 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:286 (sofia/external/8017189923@apex.local) State Change CS_ROUTING -> CS_EXECUTE 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING going to sleep 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE EXECUTE sofia/external/8017189923@apex.local set(outside_call=true) 2016-11-10 14:40:16.143933 [DEBUG] mod_dptools.c:1519 SET sofia/external/8017189923@apex.local [outside_call]=[true] EXECUTE sofia/external/8017189923@apex.local export(RFC2822_DATE=Thu, 10 Nov 2016 14:40:16 -0700) 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 Nov 2016 14:40:16 -0700] EXECUTE sofia/external/8017189923@apex.local set(domain_name=freeswitch1-stg.pci.vivint.com) 2016-11-10 14:40:16.143933 [DEBUG] mod_dptools.c:1519 SET sofia/external/8017189923@apex.local [domain_name]=[freeswitch1-stg.pci.vivint.com] EXECUTE sofia/external/8017189923@apex.local bridge(sofia/external/84810@apex.local;fs_path=sip:172.16.254.122|sofia/external/84810@apex.local;fs_path=sip:172.16.254.125) 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:1250 sofia/external/8017189923@apex.local EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 Nov 2016 14:40:16 -0700] to event 2016-11-10 14:40:16.143933 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 2016-11-10 14:40:16.143933 [NOTICE] switch_channel.c:1104 New Channel sofia/external/84810@apex.local [44b81c28-a78e-11e6-b7e9-85d38162b0e3] 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:4757 (sofia/external/84810@apex.local) State Change CS_NEW -> CS_INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:90 sofia/external/84810@apex.local SOFIA INIT 2016-11-10 14:40:16.143933 [DEBUG] sofia_glue.c:1253 sip:172.16.254.122 Setting proxy route to sofia/external/84810@apex.local 2016-11-10 14:40:16.143933 [DEBUG] sofia_glue.c:1282 sofia/external/84810@apex.local sending invite version: 1.6.12 64bit Local SDP: v=0 o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191 s=SIP Call c=IN IP4 172.16.254.191 t=0 0 m=audio 20606 RTP/AVP 0 120 c=IN IP4 172.16.254.191 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:40 sofia/external/84810@apex.local Standard INIT 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:48 (sofia/external/84810@apex.local) State Change CS_INIT -> CS_ROUTING 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT going to sleep 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_ROUTING send 1066 bytes to udp/[172.16.254.122]:5060 at 14:40:16.150561: ------------------------------------------------------------------------ INVITE sip:84810@apex.local SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKDXK2aatgeHp7p Route: Max-Forwards: 52 From: "8017189923" ;tag=X7c78r50489Qp To: Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:143 sofia/external/84810@apex.local SOFIA ROUTING Allow-Events: talk, hold, conference, refer Privacy: none 2016-11-10 14:40:16.143933 [DEBUG] switch_ivr_originate.c:67 (sofia/external/84810@apex.local) State Change CS_ROUTING -> CS_CONSUME_MEDIA Content-Type: application/sdp Content-Disposition: session Content-Length: 253 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING going to sleep X-FS-Support: update_display,send_info P-Asserted-Identity: "8017189923" v=0 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_CONSUME_MEDIA o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191 s=SIP Call c=IN IP4 172.16.254.191 t=0 0 m=audio 20606 RTP/AVP 0 120 c=IN IP4 172.16.254.191 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA going to sleep 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [calling][0] recv 282 bytes from udp/[172.16.254.122]:5060 at 14:40:16.154565: ------------------------------------------------------------------------ SIP/2.0 100 Trying Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 INVITE From: "8017189923" ;tag=X7c78r50489Qp To: Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p Content-Length: 0 ------------------------------------------------------------------------ recv 1652 bytes from udp/[172.16.254.122]:5060 at 14:40:16.197958: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Remote-Party-ID: ;party=called;screen=no;privacy=off P-Asserted-Identity: Supported: 100rel, histinfo, join, replaces, sdp-anat, timer P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" P-AV-Message-Id: 1_2 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 RSeq: 1 Require: 100rel Record-Route: Record-Route: Record-Route: Record-Route: Record-Route: Contact: Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE To: ;tag=0c81bbe41b6e615223571eb39c00 From: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ send 1028 bytes to udp/[172.16.254.122]:5060 at 14:40:16.198417: ------------------------------------------------------------------------ PRACK sip:84810@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKe6cUc5amBtctj Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=X7c78r50489Qp To: ;tag=0c81bbe41b6e615223571eb39c00 Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070945 PRACK Contact: RAck: 1 99070944 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:16.183913 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][183] 2016-11-10 14:40:16.183913 [DEBUG] sofia.c:7024 Remote SDP: v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 2016-11-10 14:40:16.183913 [NOTICE] sofia.c:7170 Pre-Answer sofia/external/84810@apex.local! 2016-11-10 14:40:16.183913 [DEBUG] switch_channel.c:3471 (sofia/external/84810@apex.local) Callstate Change DOWN -> EARLY recv 430 bytes from udp/[172.16.254.122]:5060 at 14:40:16.202892: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "8017189923" ;tag=X7c78r50489Qp To: ;tag=0c81bbe41b6e615223571eb39c00 Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070945 PRACK Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKe6cUc5amBtctj Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/external/84810@apex.local] 2016-11-10 14:40:16.203937 [INFO] switch_ivr.c:784 Sending early media 2016-11-10 14:40:16.203937 [NOTICE] mod_sofia.c:2314 Pre-Answer sofia/external/8017189923@apex.local! 2016-11-10 14:40:16.203937 [DEBUG] switch_channel.c:3471 (sofia/external/8017189923@apex.local) Callstate Change RINGING -> EARLY send 1178 bytes to udp/[172.16.254.191]:5060 at 14:40:16.212794: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191 From: ;tag=96CADFAC-220E To: ;tag=vyKe7XmX7ZK5a Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 101 INVITE Contact: RSeq: 446981481 User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Require: 100rel Supported: precondition, 100rel, timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 189 P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" P-AV-Message-Id: 1_2 P-Asserted-Identity: "84810" v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_bridge.c:1324 (sofia/external/8017189923@apex.local) State Change CS_EXECUTE -> CS_HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_bridge.c:1325 (sofia/external/84810@apex.local) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [early][183] 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] mod_sofia.c:180 sofia/external/84810@apex.local SOFIA HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:420 sofia/external/84810@apex.local Standard HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE going to sleep 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] mod_sofia.c:180 sofia/external/8017189923@apex.local SOFIA HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:420 sofia/external/8017189923@apex.local Standard HIBERNATE 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE going to sleep recv 434 bytes from udp/[172.16.254.191]:58573 at 14:40:16.214528: ------------------------------------------------------------------------ PRACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E5EB7 From: ;tag=96CADFAC-220E To: ;tag=vyKe7XmX7ZK5a Date: Thu, 10 Nov 2016 21:40:16 GMT Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 102 PRACK RAck: 446981481 101 INVITE Allow-Events: telephone-event Max-Forwards: 70 Content-Length: 0 ------------------------------------------------------------------------ send 580 bytes to udp/[172.16.254.191]:5060 at 14:40:16.214690: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E5EB7;received=172.16.254.191 From: ;tag=96CADFAC-220E To: ;tag=vyKe7XmX7ZK5a Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 102 PRACK Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 1637 bytes from udp/[172.16.254.122]:5060 at 14:40:17.170480: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Remote-Party-ID: ;party=called;screen=no;privacy=off P-Asserted-Identity: Supported: 100rel, histinfo, join, replaces, sdp-anat, timer P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" P-AV-Message-Id: 1_2 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 RSeq: 2 Require: 100rel Record-Route: Record-Route: Record-Route: Record-Route: Record-Route: Contact: Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE To: ;tag=0c81bbe41b6e615223571eb39c00 From: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ send 1028 bytes to udp/[172.16.254.122]:5060 at 14:40:17.171796: ------------------------------------------------------------------------ PRACK sip:84810@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKFF6Ke0UQ822ce Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=X7c78r50489Qp To: ;tag=0c81bbe41b6e615223571eb39c00 Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070946 PRACK Contact: RAck: 2 99070944 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:17.163941 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][180] 2016-11-10 14:40:17.163941 [DEBUG] sofia.c:7021 Duplicate SDP v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 recv 430 bytes from udp/[172.16.254.122]:5060 at 14:40:17.175453: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "8017189923" ;tag=X7c78r50489Qp To: ;tag=0c81bbe41b6e615223571eb39c00 Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070946 PRACK Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKFF6Ke0UQ822ce Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Content-Length: 0 ------------------------------------------------------------------------ recv 1680 bytes from udp/[172.16.254.122]:5060 at 14:40:18.752126: ------------------------------------------------------------------------ SIP/2.0 200 OK Remote-Party-ID: ;party=called;screen=no;privacy=off P-Asserted-Identity: Supported: 100rel, histinfo, join, replaces, sdp-anat, timer P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" P-AV-Message-Id: 1_2 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 Session-Expires: 1200;refresher=uas Require: timer Record-Route: Record-Route: Record-Route: Record-Route: Record-Route: Accept-Language: en Contact: Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE To: ;tag=0c81bbe41b6e615223571eb39c00 From: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completing][200] 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7021 Duplicate SDP v=0 o=- 1478814016 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.106 b=AS:64 t=0 0 m=audio 47932 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 send 793 bytes to udp/[172.16.254.122]:5060 at 14:40:18.753798: ------------------------------------------------------------------------ ACK sip:172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKgrZcgUcU5BSZS Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=X7c78r50489Qp To: ;tag=0c81bbe41b6e615223571eb39c00 Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 99070944 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200] 2016-11-10 14:40:18.743937 [NOTICE] sofia.c:8013 Channel [sofia/external/84810@apex.local] has been answered 2016-11-10 14:40:18.743937 [DEBUG] switch_channel.c:3770 (sofia/external/84810@apex.local) Callstate Change EARLY -> ACTIVE 2016-11-10 14:40:18.743937 [NOTICE] switch_ivr.c:779 Channel [sofia/external/8017189923@apex.local] has been answered 2016-11-10 14:40:18.743937 [DEBUG] switch_channel.c:3770 (sofia/external/8017189923@apex.local) Callstate Change EARLY -> ACTIVE send 914 bytes to udp/[172.16.254.191]:5060 at 14:40:18.755735: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191 From: ;tag=96CADFAC-220E To: ;tag=vyKe7XmX7ZK5a Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 101 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Require: timer Supported: precondition, 100rel, timer, path, replaces Allow-Events: talk, hold, conference, refer Session-Expires: 1800;refresher=uac Content-Length: 0 P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" P-AV-Message-Id: 1_2 P-Asserted-Identity: "Outbound Call" ------------------------------------------------------------------------ 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [completed][200] recv 403 bytes from udp/[172.16.254.191]:58573 at 14:40:18.757750: ------------------------------------------------------------------------ ACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57F32550 From: ;tag=96CADFAC-220E To: ;tag=vyKe7XmX7ZK5a Date: Thu, 10 Nov 2016 21:40:16 GMT Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [ready][200] freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> recv 1386 bytes from udp/[172.16.254.122]:5060 at 14:40:32.552090: ------------------------------------------------------------------------ UPDATE sip:mod_sofia@10.2.160.150:5080 SIP/2.0 Remote-Party-ID: "Brain Beebe" ;party=calling;screen=no;privacy=off P-Asserted-Identity: "Brain Beebe" Supported: 100rel, histinfo, join, replaces, sdp-anat, timer User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK497169581328340-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK497169581328340 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c823571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c823571eb39c00 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE Contact: "Brain Beebe" Accept-Language: en Min-SE: 1200 Session-Expires: 1200;refresher=uac P-Charging-Vector: icid-value="44b44bc0-a78e-11e6-a95f-0050568452e0" Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 1 UPDATE Content-Length: 0 ------------------------------------------------------------------------ send 974 bytes to udp/[172.16.254.122]:5060 at 14:40:32.552463: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK497169581328340-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK497169581328340 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c823571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c823571eb39c00 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 1 UPDATE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Require: timer Supported: precondition, 100rel, timer, path, replaces Session-Expires: 1200;refresher=uac Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.543924 [INFO] sofia.c:1278 sofia/external/84810@apex.local Update Callee ID to "Brain Beebe" <+8012297771> recv 1558 bytes from udp/[172.16.254.122]:5060 at 14:40:32.556424: ------------------------------------------------------------------------ INVITE sip:mod_sofia@10.2.160.150:5080 SIP/2.0 P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" Remote-Party-ID: "Brain Beebe" ;party=calling;screen=no;privacy=off P-Asserted-Identity: "Brain Beebe" Supported: 100rel, histinfo, join, replaces, sdp-anat, timer User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE Contact: "Brain Beebe" Accept-Language: en Min-SE: 1200 Session-Expires: 1200;refresher=uac P-Charging-Vector: icid-value="44b44bc0-a78e-11e6-a95f-0050568452e0" Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 2 INVITE Content-Length: 0 ------------------------------------------------------------------------ send 703 bytes to udp/[172.16.254.122]:5060 at 14:40:32.556731: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.543924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [received][100] send 1116 bytes to udp/[172.16.254.122]:5060 at 14:40:32.557454: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Require: timer Supported: precondition, 100rel, timer, path, replaces Session-Expires: 1200;refresher=uac Content-Type: application/sdp Content-Length: 109 v=0 o=FreeSWITCH 1478793426 1478793427 IN IP4 172.16.254.191 s=FreeSWITCH c=IN IP4 172.16.254.191 t=0 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.543924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completed][200] recv 987 bytes from udp/[172.16.254.122]:5060 at 14:40:32.625265: ------------------------------------------------------------------------ ACK sip:mod_sofia@10.2.160.150:5080 SIP/2.0 P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true" User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK345450167102362-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK345450167102362 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cb23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cb23571eb39c00 Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 815 bytes from udp/[172.16.254.122]:5060 at 14:40:32.625507: ------------------------------------------------------------------------ BYE sip:mod_sofia@10.2.160.150:5080 SIP/2.0 User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK576225203948050-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK576225203948050 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cc23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cc23571eb39c00 Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 3 BYE Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.623919 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200] 2016-11-10 14:40:32.623919 [NOTICE] sofia.c:1011 Hangup sofia/external/84810@apex.local [CS_HIBERNATE] [NORMAL_CLEARING] 2016-11-10 14:40:32.623919 [DEBUG] switch_ivr_bridge.c:1071 sofia/external/84810@apex.local skip receive message [UNBRIDGE] (channel is hungup already) 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HANGUP send 849 bytes to udp/[172.16.254.122]:5060 at 14:40:32.627166: ------------------------------------------------------------------------ SIP/2.0 200 OK 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:850 (sofia/external/84810@apex.local) Callstate Change ACTIVE -> HANGUP Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK576225203948050-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK576225203948050 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cc23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cc23571eb39c00 From: ;tag=0c81bbe41b6e615223571eb39c00 To: "8017189923" ;tag=X7c78r50489Qp Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115 CSeq: 3 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:438 Channel sofia/external/84810@apex.local hanging up, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] switch_ivr_bridge.c:1207 (sofia/external/8017189923@apex.local) State Change CS_HIBERNATE -> CS_EXECUTE 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:60 sofia/external/84810@apex.local Standard HANGUP, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:619 (sofia/external/84810@apex.local) State Change CS_HANGUP -> CS_REPORTING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_REPORTING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:174 sofia/external/84810@apex.local Standard REPORTING, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE 2016-11-10 14:40:32.623919 [NOTICE] switch_core_state_machine.c:385 sofia/external/8017189923@apex.local has executed the last dialplan instruction, hanging up. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/8017189923@apex.local [CS_EXECUTE] [NORMAL_CLEARING] 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:610 (sofia/external/84810@apex.local) State Change CS_REPORTING -> CS_DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_session.c:1647 Session 16 (sofia/external/84810@apex.local) Locked, Waiting on external entities 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1665 Session 16 (sofia/external/84810@apex.local) Ended 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/84810@apex.local [CS_DESTROY] 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HANGUP 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:741 (sofia/external/84810@apex.local) Running State Change CS_DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:850 (sofia/external/8017189923@apex.local) Callstate Change ACTIVE -> HANGUP 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:343 sofia/external/84810@apex.local SOFIA DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:181 sofia/external/84810@apex.local Standard DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:432 sofia/external/8017189923@apex.local Overriding SIP cause 480 with 200 from the other leg 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:438 Channel sofia/external/8017189923@apex.local hanging up, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/8017189923@apex.local 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:60 sofia/external/8017189923@apex.local Standard HANGUP, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:619 (sofia/external/8017189923@apex.local) State Change CS_HANGUP -> CS_REPORTING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_REPORTING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING send 588 bytes to udp/[172.16.254.191]:5060 at 14:40:32.628759: ------------------------------------------------------------------------ BYE sip:8017189923@172.16.254.191:5060 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKH1r5HpXy2mFjN Max-Forwards: 70 From: ;tag=vyKe7XmX7ZK5a To: ;tag=96CADFAC-220E Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 CSeq: 99070952 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY Supported: precondition, 100rel, timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:174 sofia/external/8017189923@apex.local Standard REPORTING, cause: NORMAL_CLEARING 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING going to sleep 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:610 (sofia/external/8017189923@apex.local) State Change CS_REPORTING -> CS_DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_session.c:1647 Session 15 (sofia/external/8017189923@apex.local) Locked, Waiting on external entities 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1665 Session 15 (sofia/external/8017189923@apex.local) Ended 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/8017189923@apex.local [CS_DESTROY] 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:741 (sofia/external/8017189923@apex.local) Running State Change CS_DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:343 sofia/external/8017189923@apex.local SOFIA DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:181 sofia/external/8017189923@apex.local Standard DESTROY 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY going to sleep recv 464 bytes from udp/[172.16.254.191]:58573 at 14:40:32.630921: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKH1r5HpXy2mFjN From: ;tag=vyKe7XmX7ZK5a To: ;tag=96CADFAC-220E Date: Thu, 10 Nov 2016 21:40:32 GMT Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191 Server: Cisco-SIPGateway/IOS-15.3.3.M5 CSeq: 99070952 BYE Reason: Q.850;cause=16 P-RTP-Stat: PS=819,OS=131040,PR=821,OR=131360,PL=0,JI=0,LA=0,DU=13 Content-Length: 0 ------------------------------------------------------------------------