recv 1220 bytes from udp/[172.16.254.192]:56031 at 14:33:32.791748: ------------------------------------------------------------------------ INVITE sip:84810@pci.vivint.com:5080 SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE From: ;tag=E583DFFA-18E6 To: Date: Thu, 10 Nov 2016 21:33:32 GMT Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 Supported: timer,resource-priority,replaces,histinfo,sdp-anat Require: 100rel Min-SE: 1800 Cisco-Guid: 0702744289-2797867494-2254422118-1667502804 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: 1478813612 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 8870 4742 IN IP4 172.16.254.192 s=SIP Call c=IN IP4 172.16.254.192 t=0 0 m=audio 18032 RTP/AVP 0 120 c=IN IP4 172.16.254.192 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 ------------------------------------------------------------------------ send 367 bytes to udp/[172.16.254.192]:5060 at 14:33:32.792134: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192 From: ;tag=E583DFFA-18E6 To: Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 CSeq: 101 INVITE Timestamp: 1478813612 0.000151 User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:32.784001 [NOTICE] switch_channel.c:1104 New Channel sofia/external/8017189923@apex.local [544cc54a-a78d-11e6-b7c7-85d38162b0e3] 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_NEW 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:9725 sofia/external/8017189923@apex.local receiving invite from 172.16.254.192:56031 version: 1.6.12 64bit 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [received][100] 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:7024 Remote SDP: v=0 o=CiscoSystemsSIP-GW-UserAgent 8870 4742 IN IP4 172.16.254.192 s=SIP Call c=IN IP4 172.16.254.192 t=0 0 m=audio 18032 RTP/AVP 0 120 c=IN IP4 172.16.254.192 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:7378 (sofia/external/8017189923@apex.local) State Change CS_NEW -> CS_INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:603 (sofia/external/8017189923@apex.local) State NEW 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:90 sofia/external/8017189923@apex.local SOFIA INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:40 sofia/external/8017189923@apex.local Standard INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:48 (sofia/external/8017189923@apex.local) State Change CS_INIT -> CS_ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT going to sleep 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_channel.c:2249 (sofia/external/8017189923@apex.local) Callstate Change DOWN -> RINGING 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:143 sofia/external/8017189923@apex.local SOFIA ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:236 sofia/external/8017189923@apex.local Standard ROUTING 2016-11-10 14:33:32.784001 [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:33:32.784001 [DEBUG] switch_core_state_machine.c:286 (sofia/external/8017189923@apex.local) State Change CS_ROUTING -> CS_EXECUTE 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING going to sleep 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE 2016-11-10 14:33:32.784001 [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:33:32.784001 [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:33:32 -0700) 2016-11-10 14:33:32.784001 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 Nov 2016 14:33:32 -0700] EXECUTE sofia/external/8017189923@apex.local set(domain_name=freeswitch1-stg.pci.vivint.com) 2016-11-10 14:33:32.784001 [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:33:32.784001 [DEBUG] switch_channel.c:1250 sofia/external/8017189923@apex.local EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 Nov 2016 14:33:32 -0700] to event 2016-11-10 14:33:32.784001 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 2016-11-10 14:33:32.784001 [NOTICE] switch_channel.c:1104 New Channel sofia/external/84810@apex.local [544d6a54-a78d-11e6-b7cf-85d38162b0e3] 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:4757 (sofia/external/84810@apex.local) State Change CS_NEW -> CS_INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:90 sofia/external/84810@apex.local SOFIA INIT 2016-11-10 14:33:32.784001 [DEBUG] sofia_glue.c:1253 sip:172.16.254.122 Setting proxy route to sofia/external/84810@apex.local 2016-11-10 14:33:32.784001 [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 8870 4742 IN IP4 172.16.254.192 s=SIP Call c=IN IP4 172.16.254.192 t=0 0 m=audio 18032 RTP/AVP 0 120 c=IN IP4 172.16.254.192 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:40 sofia/external/84810@apex.local Standard INIT 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:48 (sofia/external/84810@apex.local) State Change CS_INIT -> CS_ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT going to sleep 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:143 sofia/external/84810@apex.local SOFIA ROUTING 2016-11-10 14:33:32.784001 [DEBUG] switch_ivr_originate.c:67 (sofia/external/84810@apex.local) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING going to sleep 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_CONSUME_MEDIA 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA going to sleep send 1066 bytes to udp/[172.16.254.122]:5060 at 14:33:32.798155: ------------------------------------------------------------------------ INVITE sip:84810@apex.local SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK32paSUHDBrQcK Route: Max-Forwards: 52 From: "8017189923" ;tag=S3731c2jg5gDr To: Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 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 Allow-Events: talk, hold, conference, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 253 X-FS-Support: update_display,send_info P-Asserted-Identity: "8017189923" v=0 o=CiscoSystemsSIP-GW-UserAgent 8870 4742 IN IP4 172.16.254.192 s=SIP Call c=IN IP4 172.16.254.192 t=0 0 m=audio 18032 RTP/AVP 0 120 c=IN IP4 172.16.254.192 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=fmtp:120 0-15 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:33:32.784001 [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:33:32.800410: ------------------------------------------------------------------------ SIP/2.0 100 Trying Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 INVITE From: "8017189923" ;tag=S3731c2jg5gDr To: Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK Content-Length: 0 ------------------------------------------------------------------------ recv 1651 bytes from udp/[172.16.254.122]:5060 at 14:33:32.845615: ------------------------------------------------------------------------ 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: 544871c0-a78d-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=0464ecd40b6e6181a571eb39c00 From: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ send 1027 bytes to udp/[172.16.254.122]:5060 at 14:33:32.846279: ------------------------------------------------------------------------ PRACK sip:84810@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK4Bg3tp2g80DZe Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=S3731c2jg5gDr To: ;tag=0464ecd40b6e6181a571eb39c00 Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070743 PRACK Contact: RAck: 1 99070742 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:33:32.843921 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][183] 2016-11-10 14:33:32.843921 [DEBUG] sofia.c:7024 Remote SDP: v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 2016-11-10 14:33:32.843921 [NOTICE] sofia.c:7170 Pre-Answer sofia/external/84810@apex.local! 2016-11-10 14:33:32.843921 [DEBUG] switch_channel.c:3471 (sofia/external/84810@apex.local) Callstate Change DOWN -> EARLY recv 429 bytes from udp/[172.16.254.122]:5060 at 14:33:32.849992: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "8017189923" ;tag=S3731c2jg5gDr To: ;tag=0464ecd40b6e6181a571eb39c00 Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070743 PRACK Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK4Bg3tp2g80DZe Av-Global-Session-ID: 544871c0-a78d-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:33:32.843921 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/external/84810@apex.local] 2016-11-10 14:33:32.843921 [INFO] switch_ivr.c:784 Sending early media 2016-11-10 14:33:32.843921 [NOTICE] mod_sofia.c:2314 Pre-Answer sofia/external/8017189923@apex.local! 2016-11-10 14:33:32.843921 [DEBUG] switch_channel.c:3471 (sofia/external/8017189923@apex.local) Callstate Change RINGING -> EARLY send 1178 bytes to udp/[172.16.254.192]:5060 at 14:33:32.860896: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192 From: ;tag=E583DFFA-18E6 To: ;tag=rteB0HHFKvttc Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 CSeq: 101 INVITE Contact: RSeq: 1938420048 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=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:33:32.843921 [DEBUG] switch_ivr_bridge.c:1324 (sofia/external/8017189923@apex.local) State Change CS_EXECUTE -> CS_HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_ivr_bridge.c:1325 (sofia/external/84810@apex.local) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [early][183] 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] mod_sofia.c:180 sofia/external/8017189923@apex.local SOFIA HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:420 sofia/external/8017189923@apex.local Standard HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE going to sleep 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] mod_sofia.c:180 sofia/external/84810@apex.local SOFIA HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:420 sofia/external/84810@apex.local Standard HIBERNATE 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE going to sleep recv 435 bytes from udp/[172.16.254.192]:56031 at 14:33:32.862894: ------------------------------------------------------------------------ PRACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A11346 From: ;tag=E583DFFA-18E6 To: ;tag=rteB0HHFKvttc Date: Thu, 10 Nov 2016 21:33:32 GMT Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 CSeq: 102 PRACK RAck: 1938420048 101 INVITE Allow-Events: telephone-event Max-Forwards: 70 Content-Length: 0 ------------------------------------------------------------------------ send 580 bytes to udp/[172.16.254.192]:5060 at 14:33:32.863058: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A11346;received=172.16.254.192 From: ;tag=E583DFFA-18E6 To: ;tag=rteB0HHFKvttc Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 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 1636 bytes from udp/[172.16.254.122]:5060 at 14:33:33.811270: ------------------------------------------------------------------------ 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: 544871c0-a78d-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=0464ecd40b6e6181a571eb39c00 From: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ send 1027 bytes to udp/[172.16.254.122]:5060 at 14:33:33.811581: ------------------------------------------------------------------------ PRACK sip:84810@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK5m9UvHKm593Ha Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=S3731c2jg5gDr To: ;tag=0464ecd40b6e6181a571eb39c00 Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070744 PRACK Contact: RAck: 2 99070742 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:33:33.803924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][180] 2016-11-10 14:33:33.803924 [DEBUG] sofia.c:7021 Duplicate SDP v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 recv 429 bytes from udp/[172.16.254.122]:5060 at 14:33:33.815742: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "8017189923" ;tag=S3731c2jg5gDr To: ;tag=0464ecd40b6e6181a571eb39c00 Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070744 PRACK Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK5m9UvHKm593Ha Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0 Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705 Content-Length: 0 ------------------------------------------------------------------------ recv 1679 bytes from udp/[172.16.254.122]:5060 at 14:33:35.446840: ------------------------------------------------------------------------ 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: 544871c0-a78d-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=0464ecd40b6e6181a571eb39c00 From: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 INVITE Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK Content-Type: application/sdp Content-Length: 201 v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 ------------------------------------------------------------------------ 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completing][200] 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7021 Duplicate SDP v=0 o=- 1478813612 2 IN IP4 172.16.254.100 s=- c=IN IP4 172.16.254.107 b=AS:64 t=0 0 m=audio 18960 RTP/AVP 0 120 a=rtpmap:0 PCMU/8000 a=rtpmap:120 telephone-event/8000 a=ptime:20 send 792 bytes to udp/[172.16.254.122]:5060 at 14:33:35.448558: ------------------------------------------------------------------------ ACK sip:172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK6X2myc4Q2jt4N Route: Route: Route: Route: Route: Max-Forwards: 70 From: "8017189923" ;tag=S3731c2jg5gDr To: ;tag=0464ecd40b6e6181a571eb39c00 Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 99070742 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200] 2016-11-10 14:33:35.443934 [NOTICE] sofia.c:8013 Channel [sofia/external/84810@apex.local] has been answered 2016-11-10 14:33:35.443934 [DEBUG] switch_channel.c:3770 (sofia/external/84810@apex.local) Callstate Change EARLY -> ACTIVE send 913 bytes to udp/[172.16.254.192]:5060 at 14:33:35.450959: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192 From: ;tag=E583DFFA-18E6 To: ;tag=rteB0HHFKvttc Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 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:33:35.443934 [NOTICE] switch_ivr.c:779 Channel [sofia/external/8017189923@apex.local] has been answered 2016-11-10 14:33:35.443934 [DEBUG] switch_channel.c:3770 (sofia/external/8017189923@apex.local) Callstate Change EARLY -> ACTIVE 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [completed][200] recv 402 bytes from udp/[172.16.254.192]:56031 at 14:33:35.453268: ------------------------------------------------------------------------ ACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0 Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A51280 From: ;tag=E583DFFA-18E6 To: ;tag=rteB0HHFKvttc Date: Thu, 10 Nov 2016 21:33:32 GMT Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:35.443934 [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> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> freeswitch@freeswitch1-stg> recv 1383 bytes from udp/[172.16.254.122]:5060 at 14:33:54.206630: ------------------------------------------------------------------------ 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=z9hG4bK496700933092488-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK496700933092488 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61681a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61681a571eb39c00 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="544871c0-a78d-11e6-a95f-0050568452e0" Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 1 UPDATE Content-Length: 0 ------------------------------------------------------------------------ send 971 bytes to udp/[172.16.254.122]:5060 at 14:33:54.206938: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK496700933092488-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK496700933092488 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61681a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61681a571eb39c00 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-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:33:54.184290 [INFO] sofia.c:1278 sofia/external/84810@apex.local Update Callee ID to "Brain Beebe" <+8012297771> recv 1555 bytes from udp/[172.16.254.122]:5060 at 14:33:54.210701: ------------------------------------------------------------------------ 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=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00 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="544871c0-a78d-11e6-a95f-0050568452e0" Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 2 INVITE Content-Length: 0 ------------------------------------------------------------------------ send 700 bytes to udp/[172.16.254.122]:5060 at 14:33:54.210933: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:54.184290 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [received][100] send 1113 bytes to udp/[172.16.254.122]:5060 at 14:33:54.211871: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-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 1478795602 1478795603 IN IP4 172.16.254.192 s=FreeSWITCH c=IN IP4 172.16.254.192 t=0 0 ------------------------------------------------------------------------ 2016-11-10 14:33:54.184290 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completed][200] recv 984 bytes from udp/[172.16.254.122]:5060 at 14:33:54.252463: ------------------------------------------------------------------------ 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=z9hG4bK504520250318247-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK504520250318247 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616a1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616a1a571eb39c00 Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:54.224650 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200] recv 810 bytes from udp/[172.16.254.122]:5060 at 14:33:54.291771: ------------------------------------------------------------------------ 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=z9hG4bK71713389489978-AP;ft=172.16.254.122~13c4 Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK71713389489978 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616b1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616b1a571eb39c00 Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0 From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115 Max-Forwards: 67 CSeq: 3 BYE Content-Length: 0 ------------------------------------------------------------------------ 2016-11-10 14:33:54.283925 [NOTICE] sofia.c:1011 Hangup sofia/external/84810@apex.local [CS_HIBERNATE] [NORMAL_CLEARING] 2016-11-10 14:33:54.283925 [DEBUG] switch_ivr_bridge.c:1071 sofia/external/84810@apex.local skip receive message [UNBRIDGE] (channel is hungup already) 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HANGUP 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:850 (sofia/external/84810@apex.local) Callstate Change ACTIVE -> HANGUP send 844 bytes to udp/[172.16.254.122]:5060 at 14:33:54.293356: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK71713389489978-AP;ft=172.16.254.122~13c4 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK71713389489978 Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616b1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142 Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616b1a571eb39c00 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:438 Channel sofia/external/84810@apex.local hanging up, cause: NORMAL_CLEARING From: ;tag=0464ecd40b6e6181a571eb39c00 To: "8017189923" ;tag=S3731c2jg5gDr Call-ID: 2babe1e8-2230-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:33:54.283925 [DEBUG] switch_ivr_bridge.c:1207 (sofia/external/8017189923@apex.local) State Change CS_HIBERNATE -> CS_EXECUTE 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:60 sofia/external/84810@apex.local Standard HANGUP, cause: NORMAL_CLEARING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP going to sleep 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:619 (sofia/external/84810@apex.local) State Change CS_HANGUP -> CS_REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:174 sofia/external/84810@apex.local Standard REPORTING, cause: NORMAL_CLEARING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING going to sleep 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE 2016-11-10 14:33:54.283925 [NOTICE] switch_core_state_machine.c:385 sofia/external/8017189923@apex.local has executed the last dialplan instruction, hanging up. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/8017189923@apex.local [CS_EXECUTE] [NORMAL_CLEARING] 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:610 (sofia/external/84810@apex.local) State Change CS_REPORTING -> CS_DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_session.c:1647 Session 12 (sofia/external/84810@apex.local) Locked, Waiting on external entities 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1665 Session 12 (sofia/external/84810@apex.local) Ended 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/84810@apex.local [CS_DESTROY] 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HANGUP 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:741 (sofia/external/84810@apex.local) Running State Change CS_DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:850 (sofia/external/8017189923@apex.local) Callstate Change ACTIVE -> HANGUP 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:343 sofia/external/84810@apex.local SOFIA DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:181 sofia/external/84810@apex.local Standard DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY going to sleep 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:432 sofia/external/8017189923@apex.local Overriding SIP cause 480 with 200 from the other leg 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:438 Channel sofia/external/8017189923@apex.local hanging up, cause: NORMAL_CLEARING 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/8017189923@apex.local 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:60 sofia/external/8017189923@apex.local Standard HANGUP, cause: NORMAL_CLEARING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP going to sleep 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:619 (sofia/external/8017189923@apex.local) State Change CS_HANGUP -> CS_REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:174 sofia/external/8017189923@apex.local Standard REPORTING, cause: NORMAL_CLEARING 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING going to sleep send 588 bytes to udp/[172.16.254.192]:5060 at 14:33:54.294891: ------------------------------------------------------------------------ BYE sip:8017189923@172.16.254.192:5060 SIP/2.0 Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK76UD07mUZUgQH Max-Forwards: 70 From: ;tag=rteB0HHFKvttc To: ;tag=E583DFFA-18E6 Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 CSeq: 99070753 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:33:54.283925 [DEBUG] switch_core_state_machine.c:610 (sofia/external/8017189923@apex.local) State Change CS_REPORTING -> CS_DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_session.c:1647 Session 11 (sofia/external/8017189923@apex.local) Locked, Waiting on external entities 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1665 Session 11 (sofia/external/8017189923@apex.local) Ended 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/8017189923@apex.local [CS_DESTROY] 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:741 (sofia/external/8017189923@apex.local) Running State Change CS_DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:343 sofia/external/8017189923@apex.local SOFIA DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:181 sofia/external/8017189923@apex.local Standard DESTROY 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY going to sleep recv 466 bytes from udp/[172.16.254.192]:56031 at 14:33:54.296902: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK76UD07mUZUgQH From: ;tag=rteB0HHFKvttc To: ;tag=E583DFFA-18E6 Date: Thu, 10 Nov 2016 21:33:54 GMT Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192 Server: Cisco-SIPGateway/IOS-15.3.3.M5 CSeq: 99070753 BYE Reason: Q.850;cause=16 P-RTP-Stat: PS=1069,OS=171040,PR=1069,OR=171040,PL=0,JI=0,LA=0,DU=18 Content-Length: 0 ------------------------------------------------------------------------