[Freeswitch-users] The problem with internal calls.

Denis Jakovlev yadenis at seznam.cz
Wed Jun 17 13:20:35 MSD 2015


Hi all!

I am a little do not understand why I do not work for internal calls? 
FreeSWITCH Version 1.7.0+git~20150617T032405Z~e0edc956d8~64bit (git e0edc95 2015-06-17 03:24:05Z 64bit) on Debian

I call on the standard dialplan. For example, from 1006 to 1004. And it does not work!

Log attached. Tell me where I make a error?

thanks in advance

.=============================================================.
|   _____              ______        _____ _____ ____ _   _   |
|  |  ___| __ ___  ___/ ___\ \      / /_ _|_   _/ ___| | | |  |
|  | |_ | '__/ _ \/ _ \___ \\ \ /\ / / | |  | || |   | |_| |  |
|  |  _|| | |  __/  __/___) |\ V  V /  | |  | || |___|  _  |  |
|  |_|  |_|  \___|\___|____/  \_/\_/  |___| |_| \____|_| |_|  |
|                                                             |
.=============================================================.
|   Anthony Minessale II, Michael Jerris, Brian West, Others  |
|   FreeSWITCH (http://www.freeswitch.org)                    |
|   Paypal Donations Appreciated: paypal at freeswitch.org       |
|   Brought to you by ClueCon http://www.cluecon.com/         |
.=============================================================.

.=======================================================================================================.
|    ____ _             ____                                                                            |
|   / ___| |_   _  ___ / ___|___  _ __                                                                  |
|  | |   | | | | |/ _ \ |   / _ \| '_ \                                                                 |
|  | |___| | |_| |  __/ |__| (_) | | | |                                                                |
|   \____|_|\__,_|\___|\____\___/|_| |_|                                                                |
|                                                                                                       |
|   _____    _            _                          ____             __                                |
|  |_   _|__| | ___ _ __ | |__   ___  _ __  _   _   / ___|___  _ __  / _| ___ _ __ ___ _ __   ___ ___   |
|    | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | |   / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \  |
|    | |  __/ |  __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | |  _|  __/ | |  __/ | | | (_|  __/  |
|    |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, |  \____\___/|_| |_|_|  \___|_|  \___|_| |_|\___\___|  |
|                  |_|                      |___/                                                       |
|   _____                           _                         _                                         |
|  | ____|_   _____ _ __ _   _     / \  _   _  __ _ _   _ ___| |_                                       |
|  |  _| \ \ / / _ \ '__| | | |   / _ \| | | |/ _` | | | / __| __|                                      |
|  | |___ \ V /  __/ |  | |_| |  / ___ \ |_| | (_| | |_| \__ \ |_                                       |
|  |_____| \_/ \___|_|   \__, | /_/   \_\__,_|\__, |\__,_|___/\__|                                      |
|                        |___/                |___/                                                     |
|                                       ____ _             ____                                         |
|  __      ____      ____      __      / ___| |_   _  ___ / ___|___  _ __         ___ ___  _ __ ___     |
|  \ \ /\ / /\ \ /\ / /\ \ /\ / /     | |   | | | | |/ _ \ |   / _ \| '_ \       / __/ _ \| '_ ` _ \    |
|   \ V  V /  \ V  V /  \ V  V /   _  | |___| | |_| |  __/ |__| (_) | | | |  _  | (_| (_) | | | | | |   |
|    \_/\_/    \_/\_/    \_/\_/   (_)  \____|_|\__,_|\___|\____\___/|_| |_| (_)  \___\___/|_| |_| |_|   |
|                                                                                                       |
.=======================================================================================================.


2015-06-17 10:19:51.386028 [INFO] switch_core.c:2337 
FreeSWITCH Version 1.7.0+git~20150617T032405Z~e0edc956d8~64bit (git e0edc95 2015-06-17 03:24:05Z 64bit)

FreeSWITCH Started
Max Sessions [1000]
Session Rate [30]
SQL [Enabled]
2015-06-17 10:19:51.386034 [CONSOLE] switch_core.c:2345 
[This app Best viewed at 160x60 or more..]
recv 552 bytes from ws/[192.168.144.1]:22752 at 10:19:55.844311:
   ------------------------------------------------------------------------
   REGISTER sip:192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9575970
   Max-Forwards: 69
   To: <sip:1006 at 192.168.144.130>
   From: "1006" <sip:1006 at 192.168.144.130>;tag=nomjh3h6ae
   Call-ID: 0ttgsska8pti930806otjf
   CSeq: 6 REGISTER
   Contact: <sip:khn5qrri at v64e43th9nja.invalid;transport=ws>;+sip.ice;reg-id=1;+sip.instance="<urn:uuid:251f6006-19d0-4de0-969c-037b65fe570e>";expires=600
   Expires: 600
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: path,gruu,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
2015-06-17 10:19:55.826043 [DEBUG] sofia_reg.c:1740 Send challenge for [1006 at 192.168.144.130]
send 590 bytes to ws/[192.168.144.1]:22752 at 10:19:55.845229:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9575970;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=nomjh3h6ae
   t:<sip:1006 at 192.168.144.130>;tag=Dm05mBHK826SS
   Call-ID: 0ttgsska8pti930806otjf
   CSeq: 6 REGISTER
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   WWW-Authenticate:Digest realm="192.168.144.130",nonce="4c2e03b5-5cd2-4099-aaf7-68953bb79bb1",algorithm=MD5,qop="auth"
   l:0
   
   ------------------------------------------------------------------------
recv 795 bytes from ws/[192.168.144.1]:22752 at 10:19:55.850315:
   ------------------------------------------------------------------------
   REGISTER sip:192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9085856
   Max-Forwards: 69
   To: <sip:1006 at 192.168.144.130>
   From: "1006" <sip:1006 at 192.168.144.130>;tag=nomjh3h6ae
   Call-ID: 0ttgsska8pti930806otjf
   CSeq: 7 REGISTER
   Authorization: Digest algorithm=MD5, username="1006", realm="192.168.144.130", nonce="4c2e03b5-5cd2-4099-aaf7-68953bb79bb1", uri="sip:192.168.144.130", response="286888205e741ad5d906b976dcbf0ea1", qop=auth, cnonce="igcet3c8qps9", nc=00000001
   Contact: <sip:khn5qrri at v64e43th9nja.invalid;transport=ws>;+sip.ice;reg-id=1;+sip.instance="<urn:uuid:251f6006-19d0-4de0-969c-037b65fe570e>";expires=600
   Expires: 600
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: path,gruu,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'record_stereo' = 'true'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_gateway' = 'example.com'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_areacode' = '918'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'transfer_fallback_extension' = 'operator'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'toll_allow' = 'domestic,international,local'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'accountcode' = '1006'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'user_context' = 'default'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_name' = 'Extension 1006'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_number' = '1006'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_number' = '0000000000'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:3114 event_add_header -> 'callgroup' = 'techsupport'
2015-06-17 10:19:55.845543 [DEBUG] sofia_reg.c:1976 Register:
From:    [1006 at 192.168.144.130]
Contact: ["" <sip:khn5qrri at v64e43th9nja.invalid;transport=ws;received=192.168.144.1:22752;fs_nat=yes;fs_path=sip%3Akhn5qrri%40192.168.144.1%3A22752%3Btransport%3Dws>]
Expires: [600]
2015-06-17 10:19:55.845543 [CONSOLE] mod_voicemail.c:4066 Event Thread Started
send 590 bytes to ws/[192.168.144.1]:22752 at 10:19:55.852447:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9085856;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=nomjh3h6ae
   t:<sip:1006 at 192.168.144.130>;tag=eXSyp61p5BXcN
   Call-ID: 0ttgsska8pti930806otjf
   CSeq: 7 REGISTER
   m:<sip:khn5qrri at v64e43th9nja.invalid;transport=ws;received=192.168.144.1:22752>;expires=600
   Date:Wed, 17 Jun 2015 08:19:55 GMT
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   l:0
   
   ------------------------------------------------------------------------
send 919 bytes to ws/[192.168.144.1]:22752 at 10:19:55.853348:
   ------------------------------------------------------------------------
   NOTIFY sip:khn5qrri at v64e43th9nja.invalid;transport=ws;received=192.168.144.1:22752 SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKB26Xy3136pcZN
   Route:<sip:khn5qrri at 192.168.144.1:22752>;transport=ws
   Max-Forwards:70
   f:sip:1006 at 192.168.144.130;tag=F6jQr1jt2mKZg
   t:sip:1006 at 192.168.144.130
   i:7a42b99e-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928533 NOTIFY
   m:sip:mod_sofia at 84.242.71.194:52013
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   o:message-summary
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   Subscription-State:terminated;reason=noresource
   c:application/simple-message-summary
   l:67
   
   Messages-Waiting: no
   Message-Account: sip:1006 at 192.168.144.130
   
   ------------------------------------------------------------------------
recv 358 bytes from ws/[192.168.144.1]:22752 at 10:19:55.858447:
   ------------------------------------------------------------------------
   SIP/2.0 405 Method Not Allowed
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKB26Xy3136pcZN
   To: sip:1006 at 192.168.144.130;tag=r4kchioi0g
   From: sip:1006 at 192.168.144.130;tag=F6jQr1jt2mKZg
   Call-ID: 7a42b99e-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928533 NOTIFY
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 553 bytes from ws/[192.168.144.1]:22753 at 10:19:59.191775:
   ------------------------------------------------------------------------
   REGISTER sip:192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS f980t6k4le7l.invalid;branch=z9hG4bK6823156
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>
   From: "1004" <sip:1004 at 192.168.144.130>;tag=r2glgg5k5q
   Call-ID: m97hiai4f15tf6n7grvop9
   CSeq: 17 REGISTER
   Contact: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws>;+sip.ice;reg-id=1;+sip.instance="<urn:uuid:87e55b24-861e-42e3-be9e-06f4e677a875>";expires=600
   Expires: 600
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: path,gruu,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:1740 Send challenge for [1004 at 192.168.144.130]
send 591 bytes to ws/[192.168.144.1]:22753 at 10:19:59.193009:
   ------------------------------------------------------------------------
   SIP/2.0 401 Unauthorized
   v:SIP/2.0/WS f980t6k4le7l.invalid;branch=z9hG4bK6823156;received=192.168.144.1;rport=22753
   From: "1004" <sip:1004 at 192.168.144.130>;tag=r2glgg5k5q
   t:<sip:1004 at 192.168.144.130>;tag=gFcgtv3XZX9Hc
   Call-ID: m97hiai4f15tf6n7grvop9
   CSeq: 17 REGISTER
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   WWW-Authenticate:Digest realm="192.168.144.130",nonce="67dbaad7-b3c9-4449-8162-9843cc290c5e",algorithm=MD5,qop="auth"
   l:0
   
   ------------------------------------------------------------------------
recv 796 bytes from ws/[192.168.144.1]:22753 at 10:19:59.199117:
   ------------------------------------------------------------------------
   REGISTER sip:192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS f980t6k4le7l.invalid;branch=z9hG4bK4268115
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>
   From: "1004" <sip:1004 at 192.168.144.130>;tag=r2glgg5k5q
   Call-ID: m97hiai4f15tf6n7grvop9
   CSeq: 18 REGISTER
   Authorization: Digest algorithm=MD5, username="1004", realm="192.168.144.130", nonce="67dbaad7-b3c9-4449-8162-9843cc290c5e", uri="sip:192.168.144.130", response="e59c1006197bbe97625f8835bc204571", qop=auth, cnonce="9e872plcd06r", nc=00000001
   Contact: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws>;+sip.ice;reg-id=1;+sip.instance="<urn:uuid:87e55b24-861e-42e3-be9e-06f4e677a875>";expires=600
   Expires: 600
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: path,gruu,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'record_stereo' = 'true'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_gateway' = 'example.com'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_areacode' = '918'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'transfer_fallback_extension' = 'operator'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'toll_allow' = 'domestic,international,local'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'accountcode' = '1004'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'user_context' = 'default'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_name' = 'Extension 1004'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_number' = '1004'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_number' = '0000000000'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:3114 event_add_header -> 'callgroup' = 'techsupport'
2015-06-17 10:19:59.186303 [DEBUG] sofia_reg.c:1976 Register:
From:    [1004 at 192.168.144.130]
Contact: ["" <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753;fs_nat=yes;fs_path=sip%3Abbtn4jmc%40192.168.144.1%3A22753%3Btransport%3Dws>]
Expires: [600]
send 591 bytes to ws/[192.168.144.1]:22753 at 10:19:59.201137:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   v:SIP/2.0/WS f980t6k4le7l.invalid;branch=z9hG4bK4268115;received=192.168.144.1;rport=22753
   From: "1004" <sip:1004 at 192.168.144.130>;tag=r2glgg5k5q
   t:<sip:1004 at 192.168.144.130>;tag=Hr58UQm1v6Z4Q
   Call-ID: m97hiai4f15tf6n7grvop9
   CSeq: 18 REGISTER
   m:<sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;expires=600
   Date:Wed, 17 Jun 2015 08:19:59 GMT
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   l:0
   
   ------------------------------------------------------------------------
send 919 bytes to ws/[192.168.144.1]:22753 at 10:19:59.260350:
   ------------------------------------------------------------------------
   NOTIFY sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753 SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKcB0p0yj73Z2HH
   Route:<sip:bbtn4jmc at 192.168.144.1:22753>;transport=ws
   Max-Forwards:70
   f:sip:1004 at 192.168.144.130;tag=j1y1Xj54SFpQK
   t:sip:1004 at 192.168.144.130
   i:7c4a98a5-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928535 NOTIFY
   m:sip:mod_sofia at 84.242.71.194:52013
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   o:message-summary
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   Subscription-State:terminated;reason=noresource
   c:application/simple-message-summary
   l:67
   
   Messages-Waiting: no
   Message-Account: sip:1004 at 192.168.144.130
   
   ------------------------------------------------------------------------
recv 358 bytes from ws/[192.168.144.1]:22753 at 10:19:59.267815:
   ------------------------------------------------------------------------
   SIP/2.0 405 Method Not Allowed
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKcB0p0yj73Z2HH
   To: sip:1004 at 192.168.144.130;tag=gt9rv3dg70
   From: sip:1004 at 192.168.144.130;tag=j1y1Xj54SFpQK
   Call-ID: 7c4a98a5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928535 NOTIFY
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 5557 bytes from ws/[192.168.144.1]:22752 at 10:20:10.528845:
   ------------------------------------------------------------------------
   INVITE sip:1004 at 192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1849 INVITE
   Contact: <sip:khn5qrri at v64e43th9nja.invalid;transport=ws;ob>
   Content-Type: application/sdp
   Session-Expires: 60
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: timer,ice,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 5053
   
   v=0
   o=- 6016719078115267106 2 IN IP4 127.0.0.1
   s=-
   t=0 0
   a=group:BUNDLE audio video
   a=msid-semantic: WMS K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   m=audio 52386 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
   c=IN IP4 84.242.71.194
   a=rtcp:52389 IN IP4 84.242.71.194
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52384 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52385 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52386 typ host generation 0
   a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52387 typ host generation 0
   a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52388 typ host generation 0
   a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52389 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52386 typ srflx raddr 192.168.0.116 rport 52386 generation 0
   a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52389 typ srflx raddr 192.168.0.116 rport 52389 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:uNdvdqfbBFhUbZwq
   a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:actpass
   a=mid:audio
   a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
   a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 minptime=10; useinbandfec=1
   a=rtpmap:103 ISAC/16000
   a=rtpmap:104 ISAC/32000
   a=rtpmap:9 G722/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:106 CN/32000
   a=rtpmap:105 CN/16000
   a=rtpmap:13 CN/8000
   a=rtpmap:126 telephone-event/8000
   a=maxptime:60
   a=ssrc:1117385927 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:1117385927 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 5774d06f-dca2-489b-89b0-6a29afa18f66
   a=ssrc:1117385927 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:1117385927 label:5774d06f-dca2-489b-89b0-6a29afa18f66
   m=video 52392 RTP/SAVPF 100 116 117 96
   c=IN IP4 84.242.71.194
   a=rtcp:52395 IN IP4 84.242.71.194
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52390 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52391 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52392 typ host generation 0
   a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52393 typ host generation 0
   a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52394 typ host generation 0
   a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52395 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52392 typ srflx raddr 192.168.0.116 rport 52392 generation 0
   a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52395 typ srflx raddr 192.168.0.116 rport 52395 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:uNdvdqfbBFhUbZwq
   a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:actpass
   a=mid:video
   a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
   a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
   a=extmap:4 urn:3gpp:video-orientation
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:100 VP8/90000
   a=rtcp-fb:100 ccm fir
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=rtcp-fb:100 goog-remb
   a=rtpmap:116 red/90000
   a=rtpmap:117 ulpfec/90000
   a=rtpmap:96 rtx/90000
   a=fmtp:96 apt=100
   a=ssrc-group:FID 1533261734 2441370463
   a=ssrc:1533261734 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:1533261734 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:1533261734 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:1533261734 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:2441370463 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:2441370463 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:2441370463 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:2441370463 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   ------------------------------------------------------------------------
send 334 bytes to ws/[192.168.144.1]:22752 at 10:20:10.529402:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   To: <sip:1004 at 192.168.144.130>
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1849 INVITE
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   l:0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.525815 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/1006 at 192.168.144.130 [09929802-6ea9-40f8-a875-3f6325bf3bde]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.525815 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_NEW
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.525815 [DEBUG] sofia.c:8933 sofia/internal/1006 at 192.168.144.130 receiving invite from 192.168.144.1:22752 version: 1.7.0 git e0edc95 2015-06-17 03:24:05Z 64bit
2015-06-17 10:20:10.525815 [DEBUG] sofia_reg.c:1740 Send challenge for [1004 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.525815 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1006 at 192.168.144.130) State NEW
send 779 bytes to ws/[192.168.144.1]:22752 at 10:20:10.530455:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   t:<sip:1004 at 192.168.144.130>;tag=KartZDp8prcaF
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1849 INVITE
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Accept:application/sdp
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   Proxy-Authenticate:Digest realm="192.168.144.130",nonce="f5eaa536-0092-4d3e-bb13-4494a011ec25",algorithm=MD5,qop="auth"
   l:0
   
   ------------------------------------------------------------------------
2015-06-17 10:20:10.525815 [DEBUG] sofia.c:2147 detaching session 09929802-6ea9-40f8-a875-3f6325bf3bde
recv 272 bytes from ws/[192.168.144.1]:22752 at 10:20:10.533954:
   ------------------------------------------------------------------------
   ACK sip:1004 at 192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768
   To: <sip:1004 at 192.168.144.130>;tag=KartZDp8prcaF
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1849 ACK
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 5811 bytes from ws/[192.168.144.1]:22752 at 10:20:10.534872:
   ------------------------------------------------------------------------
   INVITE sip:1004 at 192.168.144.130 SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1850 INVITE
   Proxy-Authorization: Digest algorithm=MD5, username="1006", realm="192.168.144.130", nonce="f5eaa536-0092-4d3e-bb13-4494a011ec25", uri="sip:1004 at 192.168.144.130", response="2adf5b3f978933cac74d61632c740ba1", qop=auth, cnonce="fo5o7elbccv0", nc=00000001
   Contact: <sip:khn5qrri at v64e43th9nja.invalid;transport=ws;ob>
   Content-Type: application/sdp
   Session-Expires: 60
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: timer,ice,outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 5053
   
   v=0
   o=- 6016719078115267106 2 IN IP4 127.0.0.1
   s=-
   t=0 0
   a=group:BUNDLE audio video
   a=msid-semantic: WMS K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   m=audio 52386 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
   c=IN IP4 84.242.71.194
   a=rtcp:52389 IN IP4 84.242.71.194
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52384 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52385 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52386 typ host generation 0
   a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52387 typ host generation 0
   a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52388 typ host generation 0
   a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52389 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52386 typ srflx raddr 192.168.0.116 rport 52386 generation 0
   a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52389 typ srflx raddr 192.168.0.116 rport 52389 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:uNdvdqfbBFhUbZwq
   a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:actpass
   a=mid:audio
   a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
   a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 minptime=10; useinbandfec=1
   a=rtpmap:103 ISAC/16000
   a=rtpmap:104 ISAC/32000
   a=rtpmap:9 G722/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:106 CN/32000
   a=rtpmap:105 CN/16000
   a=rtpmap:13 CN/8000
   a=rtpmap:126 telephone-event/8000
   a=maxptime:60
   a=ssrc:1117385927 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:1117385927 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 5774d06f-dca2-489b-89b0-6a29afa18f66
   a=ssrc:1117385927 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:1117385927 label:5774d06f-dca2-489b-89b0-6a29afa18f66
   m=video 52392 RTP/SAVPF 100 116 117 96
   c=IN IP4 84.242.71.194
   a=rtcp:52395 IN IP4 84.242.71.194
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52390 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52391 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52392 typ host generation 0
   a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52393 typ host generation 0
   a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52394 typ host generation 0
   a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52395 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52392 typ srflx raddr 192.168.0.116 rport 52392 generation 0
   a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52395 typ srflx raddr 192.168.0.116 rport 52395 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:uNdvdqfbBFhUbZwq
   a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:actpass
   a=mid:video
   a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
   a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
   a=extmap:4 urn:3gpp:video-orientation
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:100 VP8/90000
   a=rtcp-fb:100 ccm fir
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=rtcp-fb:100 goog-remb
   a=rtpmap:116 red/90000
   a=rtpmap:117 ulpfec/90000
   a=rtpmap:96 rtx/90000
   a=fmtp:96 apt=100
   a=ssrc-group:FID 1533261734 2441370463
   a=ssrc:1533261734 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:1533261734 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:1533261734 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:1533261734 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:2441370463 cname:HkB8ZSFBev4Xlwj5
   a=ssrc:2441370463 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   a=ssrc:2441370463 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
   a=ssrc:2441370463 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
   ------------------------------------------------------------------------
send 334 bytes to ws/[192.168.144.1]:22752 at 10:20:10.535175:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   To: <sip:1004 at 192.168.144.130>
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1850 INVITE
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   l:0
   
   ------------------------------------------------------------------------
2015-06-17 10:20:10.525815 [DEBUG] sofia.c:2255 Re-attaching to session 09929802-6ea9-40f8-a875-3f6325bf3bde
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:8933 sofia/internal/1006 at 192.168.144.130 receiving invite from 192.168.144.1:22752 version: 1.7.0 git e0edc95 2015-06-17 03:24:05Z 64bit
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'record_stereo' = 'true'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_gateway' = 'example.com'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'default_areacode' = '918'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'transfer_fallback_extension' = 'operator'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'toll_allow' = 'domestic,international,local'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'accountcode' = '1006'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'user_context' = 'default'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_name' = 'Extension 1006'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'effective_caller_id_number' = '1006'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'outbound_caller_id_number' = '0000000000'
2015-06-17 10:20:10.545814 [DEBUG] sofia_reg.c:3114 event_add_header -> 'callgroup' = 'techsupport'
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:10198 Setting NAT mode based on websockets
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:6701 Channel sofia/internal/1006 at 192.168.144.130 entering state [received][100]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:6711 Remote SDP:
09929802-6ea9-40f8-a875-3f6325bf3bde v=0
09929802-6ea9-40f8-a875-3f6325bf3bde o=- 6016719078115267106 2 IN IP4 127.0.0.1
09929802-6ea9-40f8-a875-3f6325bf3bde s=-
09929802-6ea9-40f8-a875-3f6325bf3bde t=0 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=group:BUNDLE audio video
09929802-6ea9-40f8-a875-3f6325bf3bde a=msid-semantic: WMS K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde m=audio 52386 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:111 opus/48000/2
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:111 minptime=10; useinbandfec=1
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:103 ISAC/16000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:104 ISAC/32000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:9 G722/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:0 PCMU/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:8 PCMA/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:106 CN/32000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:105 CN/16000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:13 CN/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:126 telephone-event/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:52389 IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52384 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52385 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52386 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52387 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52388 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52389 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52386 typ srflx raddr 192.168.0.116 rport 52386 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52389 typ srflx raddr 192.168.0.116 rport 52389 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:uNdvdqfbBFhUbZwq
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:actpass
09929802-6ea9-40f8-a875-3f6325bf3bde a=mid:audio
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=maxptime:60
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 5774d06f-dca2-489b-89b0-6a29afa18f66
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 label:5774d06f-dca2-489b-89b0-6a29afa18f66
09929802-6ea9-40f8-a875-3f6325bf3bde m=video 52392 RTP/SAVPF 100 116 117 96
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:100 VP8/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:116 red/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:117 ulpfec/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:96 rtx/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:96 apt=100
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:52395 IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52390 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52391 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52392 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52393 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52394 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52395 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52392 typ srflx raddr 192.168.0.116 rport 52392 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52395 typ srflx raddr 192.168.0.116 rport 52395 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:uNdvdqfbBFhUbZwq
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:actpass
09929802-6ea9-40f8-a875-3f6325bf3bde a=mid:video
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:4 urn:3gpp:video-orientation
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 ccm fir
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack pli
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 goog-remb
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc-group:FID 1533261734 2441370463
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde 
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:6977 (sofia/internal/1006 at 192.168.144.130) State Change CS_NEW -> CS_INIT
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_INIT
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1006 at 192.168.144.130) State INIT
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:88 sofia/internal/1006 at 192.168.144.130 SOFIA INIT
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1006 at 192.168.144.130 Standard INIT
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1006 at 192.168.144.130) State Change CS_INIT -> CS_ROUTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1006 at 192.168.144.130) State INIT going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_ROUTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:2239 (sofia/internal/1006 at 192.168.144.130) Callstate Change DOWN -> RINGING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1006 at 192.168.144.130) State ROUTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:141 sofia/internal/1006 at 192.168.144.130 SOFIA ROUTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1006 at 192.168.144.130 Standard ROUTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] mod_dialplan_xml.c:642 Processing 1006 <1006>->1004 in context default
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->unloop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->tod_example] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Date/Time Match (PASS) [tod_example] break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(open=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->holiday_example] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Date/TimeMatch (FAIL) [holiday_example] break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->global-intercept] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [global-intercept] destination_number(1004) =~ /^886$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->group-intercept] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [group-intercept] destination_number(1004) =~ /^\*8$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->intercept-ext] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [intercept-ext] destination_number(1004) =~ /^\*\*(\d+)$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->redial] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [redial] destination_number(1004) =~ /^(redial|870)$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->global] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (PASS) [global] ${call_debug}(true) =~ /^true$/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action info()
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [global] ${default_password}(19551004) =~ /^1234$/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [global] ${switch_r_sdp}(v=0
09929802-6ea9-40f8-a875-3f6325bf3bde o=- 6016719078115267106 2 IN IP4 127.0.0.1
09929802-6ea9-40f8-a875-3f6325bf3bde s=-
09929802-6ea9-40f8-a875-3f6325bf3bde t=0 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=group:BUNDLE audio video
09929802-6ea9-40f8-a875-3f6325bf3bde a=msid-semantic: WMS K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde m=audio 52386 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:111 opus/48000/2
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:111 minptime=10; useinbandfec=1
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:103 ISAC/16000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:104 ISAC/32000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:9 G722/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:0 PCMU/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:8 PCMA/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:106 CN/32000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:105 CN/16000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:13 CN/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:126 telephone-event/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:52389 IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52384 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52385 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52386 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52387 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52388 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52389 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52386 typ srflx raddr 192.168.0.116 rport 52386 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52389 typ srflx raddr 192.168.0.116 rport 52389 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:uNdvdqfbBFhUbZwq
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:actpass
09929802-6ea9-40f8-a875-3f6325bf3bde a=mid:audio
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=maxptime:60
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 5774d06f-dca2-489b-89b0-6a29afa18f66
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1117385927 label:5774d06f-dca2-489b-89b0-6a29afa18f66
09929802-6ea9-40f8-a875-3f6325bf3bde m=video 52392 RTP/SAVPF 100 116 117 96
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:100 VP8/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:116 red/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:117 ulpfec/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:96 rtx/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:96 apt=100
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:52395 IN IP4 84.242.71.194
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52390 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52391 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52392 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52393 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52394 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52395 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52392 typ srflx raddr 192.168.0.116 rport 52392 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52395 typ srflx raddr 192.168.0.116 rport 52395 generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:153724957 2 tcp 1518280446 192.168.144.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:1020564722 2 tcp 1518214910 192.168.253.1 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:696762970 2 tcp 1518149374 192.168.0.116 0 typ host tcptype active generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:uNdvdqfbBFhUbZwq
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:ivU0QmWE3Wgg4FmQJ3qODeUK
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:actpass
09929802-6ea9-40f8-a875-3f6325bf3bde a=mid:video
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
09929802-6ea9-40f8-a875-3f6325bf3bde a=extmap:4 urn:3gpp:video-orientation
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 ccm fir
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack pli
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 goog-remb
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc-group:FID 1533261734 2441370463
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1533261734 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 cname:HkB8ZSFBev4Xlwj5
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 msid:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ 0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 mslabel:K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2441370463 label:0aad4fc5-9ffe-4b6e-ac03-b68cc2d15155
09929802-6ea9-40f8-a875-3f6325bf3bde ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Absolute Condition [global]
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action hash(insert/${domain_name}-last_dial/global/${uuid})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->snom-demo-2] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [snom-demo-2] destination_number(1004) =~ /^9001$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->snom-demo-1] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [snom-demo-1] destination_number(1004) =~ /^9000$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->eavesdrop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [eavesdrop] destination_number(1004) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->eavesdrop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [eavesdrop] destination_number(1004) =~ /^779$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->call_return] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [call_return] destination_number(1004) =~ /^\*69$|^869$|^lcr$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->del-group] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [del-group] destination_number(1004) =~ /^80(\d{2})$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->add-group] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [add-group] destination_number(1004) =~ /^81(\d{2})$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->call-group-simo] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [call-group-simo] destination_number(1004) =~ /^82(\d{2})$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->call-group-order] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [call-group-order] destination_number(1004) =~ /^83(\d{2})$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->extension-intercom] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (FAIL) [extension-intercom] destination_number(1004) =~ /^8(10[01][0-9])$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 parsing [default->Local_Extension] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Regex (PASS) [Local_Extension] destination_number(1004) =~ /^(10[01][0-9])$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action export(dialed_extension=1004)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bind_meta_app(1 b s execute_extension::dx XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bind_meta_app(3 b s execute_extension::cf XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(ringback=${us-ring})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(transfer_ringback=local_stream://moh)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(call_timeout=30)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(hangup_after_bridge=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action set(uuid_bridge_continue_on_cancel=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bridge({ignore_early_media=true}user/${dialed_extension}@${domain_name})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action answer()
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action sleep(1000)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006 at 192.168.144.130 Action bridge({ignore_early_media=true}loopback/app=voicemail:default ${domain_name} ${dialed_extension})
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1006 at 192.168.144.130) State Change CS_ROUTING -> CS_EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1006 at 192.168.144.130) State ROUTING going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1006 at 192.168.144.130) State EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:196 sofia/internal/1006 at 192.168.144.130 SOFIA EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1006 at 192.168.144.130 Standard EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(open=true)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [open]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 info()
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] mod_dptools.c:1681 CHANNEL_DATA:
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-State: [CS_EXECUTE]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-Call-State: [RINGING]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-State-Number: [4]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-Name: [sofia/internal/1006 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde Unique-ID: [09929802-6ea9-40f8-a875-3f6325bf3bde]
09929802-6ea9-40f8-a875-3f6325bf3bde Call-Direction: [inbound]
09929802-6ea9-40f8-a875-3f6325bf3bde Presence-Call-Direction: [inbound]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-HIT-Dialplan: [true]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-Presence-ID: [1006 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde Channel-Call-UUID: [09929802-6ea9-40f8-a875-3f6325bf3bde]
09929802-6ea9-40f8-a875-3f6325bf3bde Answer-State: [ringing]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Direction: [inbound]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Logical-Direction: [inbound]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Username: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Dialplan: [XML]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Caller-ID-Name: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Caller-ID-Number: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Orig-Caller-ID-Name: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Orig-Caller-ID-Number: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Network-Addr: [192.168.144.1]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-ANI: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Destination-Number: [1004]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Unique-ID: [09929802-6ea9-40f8-a875-3f6325bf3bde]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Source: [mod_sofia]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Context: [default]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Name: [sofia/internal/1006 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Profile-Index: [1]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Profile-Created-Time: [1434529210545814]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Created-Time: [1434529210545814]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Answered-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Progress-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Progress-Media-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Hangup-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Transfer-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Resurrect-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Bridged-Time: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Last-Hold: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Channel-Hold-Accum: [0]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Screen-Bit: [true]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Privacy-Hide-Name: [false]
09929802-6ea9-40f8-a875-3f6325bf3bde Caller-Privacy-Hide-Number: [false]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_direction: [inbound]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_uuid: [09929802-6ea9-40f8-a875-3f6325bf3bde]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_session_id: [1]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_user: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_uri: [1006 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_host: [192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_video_media_flow: [sendrecv]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_audio_media_flow: [sendrecv]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_channel_name: [sofia/internal/1006 at 192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_call_id: [e46qgviqdnnt7tat85f0]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_ep_codec_string: [mod_opus.opus at 48000h@20i at 2c,mod_spandsp.G722 at 8000h@20i at 64000b,CORE_PCM_MODULE.PCMU at 8000h@20i at 64000b,CORE_PCM_MODULE.PCMA at 8000h@20i at 64000b,mod_vpx.VP8]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_local_network_addr: [84.242.71.194]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_network_ip: [192.168.144.1]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_network_port: [22752]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_received_ip: [192.168.144.1]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_received_port: [22752]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_via_protocol: [ws]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_authorized: [true]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Name: [REQUEST_PARAMS]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Core-UUID: [a49b3b9f-ffd3-41d7-9086-633a6baaf890]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_FreeSWITCH-Hostname: [debian]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_FreeSWITCH-Switchname: [debian]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_FreeSWITCH-IPv4: [192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_FreeSWITCH-IPv6: [::1]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Date-Local: [2015-06-17 10:20:10]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Date-GMT: [Wed, 17 Jun 2015 08:20:10 GMT]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Date-Timestamp: [1434529210545814]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Calling-File: [sofia.c]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Calling-Function: [sofia_handle_sip_i_invite]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Calling-Line-Number: [9141]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_Event-Sequence: [545]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_number_alias: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_auth_username: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_auth_realm: [192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_number_alias: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_requested_domain_name: [192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_record_stereo: [true]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_default_gateway: [example.com]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_default_areacode: [918]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_transfer_fallback_extension: [operator]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_toll_allow: [domestic,international,local]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_accountcode: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_user_context: [default]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_effective_caller_id_name: [Extension 1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_effective_caller_id_number: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_outbound_caller_id_name: [FreeSWITCH]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_outbound_caller_id_number: [0000000000]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_callgroup: [techsupport]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_user_name: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_domain_name: [192.168.144.130]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_user_stripped: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_tag: [flk0cbpd3o]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sofia_profile_name: [internal]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_recovery_profile_name: [internal]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_full_via: [SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282;received=192.168.144.1;rport=22752]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_from_display: [1006]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_full_from: ["1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_full_to: [<sip:1004 at 192.168.144.130>]
variable_sip_req_user: [1004]
variable_sip_req_uri: [1004 at 192.168.144.130]
variable_sip_req_host: [192.168.144.130]
variable_sip_to_user: [1004]
variable_sip_to_uri: [1004 at 192.168.144.130]
variable_sip_to_host: [192.168.144.130]
variable_sip_contact_params: [transport=ws;ob]
variable_sip_contact_user: [khn5qrri]
variable_sip_contact_uri: [khn5qrri at v64e43th9nja.invalid]
variable_sip_contact_host: [v64e43th9nja.invalid]
variable_rtp_use_codec_string: [OPUS,G722,PCMU,PCMA,VP8]
variable_sip_user_agent: [JsSIP 0.6.5]
variable_sip_via_host: [v64e43th9nja.invalid]
variable_sip_via_rport: [22752]
variable_max_forwards: [69]
variable_presence_id: [1006 at 192.168.144.130]
variable_sip_nat_detected: [true]
variable_switch_r_sdp: [v=0
o=- 6016719078115267106 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio video
a=msid-semantic: WMS K79PUkyjG8yUm2Ms8eIi7q0hJ3EWRJtnl2HJ
m=audio 52386 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
c=IN IP4 84.242.71.194
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10; useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:126 telephone-event/8000
a=rtcp:52389 IN IP4 84.242.71.194
a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52384 typ host generation 0
a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52385 typ host generation 0
a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52386 typ host generation 0
a=candidate:1202345197 2 udp 2122260222 192.168.144.1 52387 typ host generation 0
a=candidate:1918330882 2 udp 2122194686 192.168.253.1 52388 typ host generation 0
a=candidate:1728331434 2 udp 2122129150 192.168.0.116 52389 typ host generation 0
a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52386 typ srflx raddr 192.168.0.116 rport 52386 generation 0
a=candidate:3863754782 2 udp 1685921534 84.242.71.194 52389 typ srfl09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 hash(insert/192.168.144.130-spymap/1006/09929802-6ea9-40f8-a875-3f6325bf3bde)
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 hash(insert/192.168.144.130-last_dial/1006/1004)
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 hash(insert/192.168.144.130-last_dial/global/09929802-6ea9-40f8-a875-3f6325bf3bde)
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 export(RFC2822_DATE=Wed, 17 Jun 2015 10:20:10 +0200)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1281 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 17 Jun 2015 10:20:10 +0200]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 export(dialed_extension=1004)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1281 EXPORT (export_vars) [dialed_extension]=[1004]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 bind_meta_app(1 b s execute_extension::dx XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] switch_ivr_async.c:4066 Bound B-Leg: *1 execute_extension::dx XML features
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1006.2015-06-17-10-20-10.wav)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] switch_ivr_async.c:4066 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1006.2015-06-17-10-20-10.wav
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 bind_meta_app(3 b s execute_extension::cf XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] switch_ivr_async.c:4066 Bound B-Leg: *3 execute_extension::cf XML features
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 bind_meta_app(4 b s execute_extension::att_xfer XML features)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [INFO] switch_ivr_async.c:4066 Bound B-Leg: *4 execute_extension::att_xfer XML features
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(ringback=%(2000,4000,440,480))
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [ringback]=[%(2000,4000,440,480)]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(transfer_ringback=local_stream://moh)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [transfer_ringback]=[local_stream://moh]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(call_timeout=30)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [call_timeout]=[30]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(hangup_after_bridge=true)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [hangup_after_bridge]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 set(uuid_bridge_continue_on_cancel=true)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] mod_dptools.c:1469 sofia/internal/1006 at 192.168.144.130 SET [uuid_bridge_continue_on_cancel]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006 at 192.168.144.130 bridge({ignore_early_media=true}user/1004 at 192.168.144.130)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1235 sofia/internal/1006 at 192.168.144.130 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 17 Jun 2015 10:20:10 +0200] to event
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1235 sofia/internal/1006 at 192.168.144.130 EXPORTING[export_vars] [dialed_extension]=[1004] to event
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_ivr_originate.c:2103 Parsing global variables
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1235 sofia/internal/1006 at 192.168.144.130 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 17 Jun 2015 10:20:10 +0200] to event
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1235 sofia/internal/1006 at 192.168.144.130 EXPORTING[export_vars] [dialed_extension]=[1004] to event
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_ivr_originate.c:2103 Parsing global variables
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid [d75b158c-5a16-43c3-acb7-b956e42de156]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:4734 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State Change CS_NEW -> CS_INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:88 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid SOFIA INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] sofia_glue.c:1223 sip:bbtn4jmc at 192.168.144.1:22753;transport=ws Setting proxy route to sofia/internal/bbtn4jmc at f980t6k4le7l.invalid
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] sofia_glue.c:1252 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid sending invite version: 1.7.0 git e0edc95 2015-06-17 03:24:05Z 64bit
d75b158c-5a16-43c3-acb7-b956e42de156 Local SDP:
d75b158c-5a16-43c3-acb7-b956e42de156 v=0
d75b158c-5a16-43c3-acb7-b956e42de156 o=FreeSWITCH 1434497130 1434497131 IN IP4 192.168.144.130
d75b158c-5a16-43c3-acb7-b956e42de156 s=FreeSWITCH
d75b158c-5a16-43c3-acb7-b956e42de156 c=IN IP4 192.168.144.130
d75b158c-5a16-43c3-acb7-b956e42de156 t=0 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=msid-semantic: WMS BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
d75b158c-5a16-43c3-acb7-b956e42de156 m=audio 32080 RTP/SAVPF 111 9 0 8 101
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:111 opus/48000/2
d75b158c-5a16-43c3-acb7-b956e42de156 a=fmtp:111 minptime=10; useinbandfec=1
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:9 G722/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:0 PCMU/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:8 PCMA/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:101 telephone-event/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
d75b158c-5a16-43c3-acb7-b956e42de156 a=setup:actpass
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-mux
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp:32080 IN IP4 192.168.144.130
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:696542618 cname:Hb8wolAUb2yjmKSx
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:696542618 msid:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4 a0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:696542618 mslabel:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:696542618 label:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4a0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-ufrag:yhNwO9GwVJbouij1
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-pwd:jinytOiuHGEThnhGvU29TzvE
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:0302657138 1 udp 659136 192.168.144.130 32080 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:0302657138 2 udp 659136 192.168.144.130 32080 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ptime:20
d75b158c-5a16-43c3-acb7-b956e42de156 a=sendrecv
d75b158c-5a16-43c3-acb7-b956e42de156 m=video 27260 RTP/SAVPF 100
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:100 VP8/90000
d75b158c-5a16-43c3-acb7-b956e42de156 a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
d75b158c-5a16-43c3-acb7-b956e42de156 a=setup:actpass
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-mux
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp:27260 IN IP4 192.168.144.130
d75b158c-5a16-43c3-acb7-b956e42de156 b=AS:1024
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-fb:100 ccm fir tmmbr
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-fb:100 nack
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-fb:100 nack pli
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:4274266173 cname:Hb8wolAUb2yjmKSx
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:4274266173 msid:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4 v0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:4274266173 mslabel:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:4274266173 label:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4v0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-ufrag:SpCSA7i6VRWi3BD4
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-pwd:hGpS28uLDzHikno3CRL3OT0A
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:9442096508 1 udp 659136 192.168.144.130 27260 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:9442096508 2 udp 659135 192.168.144.130 27260 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:40 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Standard INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State Change CS_INIT -> CS_ROUTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State INIT going to sleep
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_ROUTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State ROUTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:141 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid SOFIA ROUTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State ROUTING going to sleep
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_CONSUME_MEDIA
send 2793 bytes to ws/[192.168.144.1]:22753 at 10:20:10.559976:
   ------------------------------------------------------------------------
   INVITE sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753 SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKDmSF2S3a18r4c
   Route:<sip:bbtn4jmc at 192.168.144.1:22753>;transport=ws
   Max-Forwards:68
   f:"Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   t:<sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>
   i:8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928541 INVITE
   m:<sip:mod_sofia at 84.242.71.194:52013>
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   c:application/sdp
   Content-Disposition:session
   l:1788
   X-FS-Support:update_display,send_info
   Remote-Party-ID:"Extension 1006"<sip:1006 at 192.168.144.130>;party=calling;screen=yes;privacy=off
   
   v=0
   o=FreeSWITCH 1434497130 1434497131 IN IP4 192.168.144.130
   s=FreeSWITCH
   c=IN IP4 192.168.144.130
   t=0 0
   a=msid-semantic: WMS BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
   m=audio 32080 RTP/SAVPF 111 9 0 8 101
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 minptime=10; useinbandfec=1
   a=rtpmap:9 G722/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:actpass
   a=rtcp-mux
   a=rtcp:32080 IN IP4 192.168.144.130
   a=ssrc:696542618 cname:Hb8wolAUb2yjmKSx
   a=ssrc:696542618 msid:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4 a0
   a=ssrc:696542618 mslabel:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
   a=ssrc:696542618 label:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4a0
   a=ice-ufrag:yhNwO9GwVJbouij1
   a=ice-pwd:jinytOiuHGEThnhGvU29TzvE
   a=candidate:0302657138 1 udp 659136 192.168.144.130 32080 typ host generation 0
   a=candidate:0302657138 2 udp 659136 192.168.144.130 32080 typ host generation 0
   a=ptime:20
   m=video 27260 RTP/SAVPF 100
   b=AS:1024
   a=rtpmap:100 VP8/90000
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:actpass
   a=rtcp-mux
   a=rtcp:27260 IN IP4 192.168.144.130
   a=rtcp-fb:100 ccm fir tmmbr
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=ssrc:4274266173 cname:Hb8wolAUb2yjmKSx
   a=ssrc:4274266173 msid:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4 v0
   a=ssrc:4274266173 mslabel:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4
   a=ssrc:4274266173 label:BW4dZnmdF0COhtmIkYzq7GvkSWcPDUg4v0
   a=ice-ufrag:SpCSA7i6VRWi3BD4
   a=ice-pwd:hGpS28uLDzHikno3CRL3OT0A
   a=candidate:9442096508 1 udp 659136 192.168.144.130 27260 typ host generation 0
   a=candidate:9442096508 2 udp 659135 192.168.144.130 27260 typ host generation 0
   ------------------------------------------------------------------------
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State CONSUME_MEDIA
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State CONSUME_MEDIA going to sleep
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] sofia.c:6701 Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid entering state [calling][0]
recv 359 bytes from ws/[192.168.144.1]:22753 at 10:20:10.566722:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKDmSF2S3a18r4c
   To: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>
   From: "Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928541 INVITE
   Supported: timer,ice,outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
recv 434 bytes from ws/[192.168.144.1]:22753 at 10:20:10.572415:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKDmSF2S3a18r4c
   To: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   From: "Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928541 INVITE
   Contact: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws>
   Supported: timer,ice,outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.565818 [DEBUG] sofia.c:6701 Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid entering state [proceeding][180]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.565818 [NOTICE] sofia.c:6803 Ring-Ready sofia/internal/bbtn4jmc at f980t6k4le7l.invalid!
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.565818 [DEBUG] switch_channel.c:3332 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Callstate Change DOWN -> RINGING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [INFO] switch_ivr_originate.c:1193 Sending early media
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:105:16000:60:0:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:105:16000:60:0:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:13:8000:60:0:1]/[opus:116:48000:20:0:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:13:8000:60:0:1]/[G722:9:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMU:0:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMA:8:8000:20:64000:1]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3978 Set telephone-event payload to 126
2015-06-17 10:20:10.565818 [DEBUG] mod_opus.c:341 Opus encoder set bitrate to local settings [-1000bps]
2015-06-17 10:20:10.565818 [DEBUG] mod_opus.c:341 Opus encoder set bitrate to local settings [-1000bps]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:2848 Set Codec sofia/internal/1006 at 192.168.144.130 opus/48000 20 ms 960 samples 0 bits 1 channels
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_codec.c:111 sofia/internal/1006 at 192.168.144.130 Original read codec set to opus:116
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [WARNING] switch_core_media.c:3184 NO candidate ACL defined, Defaulting to wan.auto
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.144.1:52384
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.253.1:52385
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.116:52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.144.1:52387
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.253.1:52388
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.116:52389
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: srflx addr: 84.242.71.194:52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 2 proto: udp type: srflx addr: 84.242.71.194:52389
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3253 Searching for rtp candidate.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3258 Choose rtp candidate, index 3, 84.242.71.194:52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3076 sofia/internal/1006 at 192.168.144.130 choosing family v4
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3269 Choose same candidate, index 4, for rtcp based on rtcp-mux attribute 84.242.71.194:52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3314 setting remote audio ice addr to index 3 84.242.71.194:52386 based on candidate
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3335 Setting remote rtcp audio addr to 84.242.71.194:52386 based on candidate
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4329 Set 2833 dtmf send/recv payload to 126
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4475 Video Codec Compare [VP8:100]/[VP8:99]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4487 Video Codec Compare [VP8:99] +++ is saved as a match
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4475 Video Codec Compare [red:116]/[VP8:99]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4475 Video Codec Compare [ulpfec:117]/[VP8:99]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:4475 Video Codec Compare [rtx:96]/[VP8:99]
2015-06-17 10:20:10.565818 [DEBUG] mod_vpx.c:504 VPX VER:v1.4.0 VPX_IMAGE_ABI_VERSION:3 VPX_CODEC_ABI_VERSION:6
2015-06-17 10:20:10.565818 [DEBUG] mod_vpx.c:504 VPX VER:v1.4.0 VPX_IMAGE_ABI_VERSION:3 VPX_CODEC_ABI_VERSION:6
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:2664 Set VIDEO Codec sofia/internal/1006 at 192.168.144.130 VP8/90000 0 ms
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [WARNING] switch_core_media.c:3184 NO candidate ACL defined, Defaulting to wan.auto
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.144.1:52390
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.253.1:52391
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.0.116:52392
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 2 proto: udp type: host addr: 192.168.144.1:52393
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 2 proto: udp type: host addr: 192.168.253.1:52394
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 2 proto: udp type: host addr: 192.168.0.116:52395
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: srflx addr: 84.242.71.194:52392
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 2 proto: udp type: srflx addr: 84.242.71.194:52395
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3253 Searching for rtp candidate.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3258 Choose rtp candidate, index 3, 84.242.71.194:52392
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3076 sofia/internal/1006 at 192.168.144.130 choosing family v4
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3269 Choose same candidate, index 4, for rtcp based on rtcp-mux attribute 84.242.71.194:52392
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3314 setting remote video ice addr to index 3 84.242.71.194:52392 based on candidate
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:3335 Setting remote rtcp video addr to 84.242.71.194:52392 based on candidate
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_core_media.c:5885 AUDIO RTP [sofia/internal/1006 at 192.168.144.130] 192.168.144.130 port 30612 -> 84.242.71.194 port 52386 codec: 111 ms: 20
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.565818 [DEBUG] switch_rtp.c:3682 Starting timer [soft] 960 bytes per 20ms
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6060 Activating Audio ICE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [NOTICE] switch_rtp.c:4201 Activating RTP audio ICE: uNdvdqfbBFhUbZwq:NBzX7USM62b5UR7h 84.242.71.194:52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6103 Activating RTCP PORT 52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_rtp.c:4098 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 52386
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6111 Skipping RTCP ICE (Same as RTP)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_rtp.c:3212 Activate RTP/RTCP audio DTLS client
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_rtp.c:3332 Changing audio DTLS state from OFF to HANDSHAKE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:6184 Set 2833 dtmf send payload to 126
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:6190 Set 2833 dtmf receive payload to 126
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:6212 sofia/internal/1006 at 192.168.144.130 Set rtp dtmf delay to 40
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_rtp.c:3693 Starting video timer.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:6401 VIDEO RTP [sofia/internal/1006 at 192.168.144.130] 192.168.144.130:28128->84.242.71.194:52392 codec: 100 ms: 0 [SUCCESS]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:5063 sofia/internal/1006 at 192.168.144.130 Starting Video thread
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6442 Activating Video ICE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [NOTICE] switch_rtp.c:4201 Activating RTP video ICE: uNdvdqfbBFhUbZwq:WuOzDUI2IaupUY58 84.242.71.194:52392
2015-06-17 10:20:10.585823 [DEBUG] switch_core_media.c:4902 sofia/internal/1006 at 192.168.144.130 Video thread started. Echo is off
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6480 Activating VIDEO RTCP PORT 52392 interval 2000 mux 1
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_rtp.c:4098 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 52392
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_core_media.c:6491 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_rtp.c:3212 Activate RTP/RTCP video DTLS client
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [INFO] switch_rtp.c:3332 Changing video DTLS state from OFF to HANDSHAKE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1006 at 192.168.144.130!
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_channel.c:3460 (sofia/internal/1006 at 192.168.144.130) Callstate Change RINGING -> EARLY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] mod_sofia.c:2292 Ring SDP:
09929802-6ea9-40f8-a875-3f6325bf3bde v=0
09929802-6ea9-40f8-a875-3f6325bf3bde o=FreeSWITCH 1434498598 1434498599 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde s=FreeSWITCH
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde t=0 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=msid-semantic: WMS cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde m=audio 30612 RTP/SAVPF 111 126
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:111 opus/48000/2
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:111 useinbandfec=1; minptime=10
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:126 telephone-event/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=ptime:20
09929802-6ea9-40f8-a875-3f6325bf3bde a=sendrecv
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:active
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:30612 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:NBzX7USM62b5UR7h
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:7gwWPgx66SYWrDW45lS26BF2
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:7309048561 1 udp 659136 192.168.144.130 30612 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 cname:wpipZITKqZv20IO9
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc a0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pca0
09929802-6ea9-40f8-a875-3f6325bf3bde m=video 28128 RTP/SAVPF 100
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:100 VP8/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:active
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:28128 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde b=AS:1024
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 ccm fir
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack pli
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 cname:wpipZITKqZv20IO9
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc v0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pcv0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:WuOzDUI2IaupUY58
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:dwo33dpSyZDos77erMEVwcN4
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:5716597382 1 udp 659136 192.168.144.130 28128 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde 
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_ivr_originate.c:1251 Raw Codec Activation Success L16 at 48000hz 1 channel 20ms
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_core_codec.c:221 sofia/internal/1006 at 192.168.144.130 Push codec L16:100
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_ivr_originate.c:1319 Play Ringback Tone [%(2000,4000,440,480)]
send 2380 bytes to ws/[192.168.144.1]:22752 at 10:20:10.590146:
   ------------------------------------------------------------------------
   SIP/2.0 183 Session Progress
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   t:<sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1850 INVITE
   m:<sip:1004 at 84.242.71.194:52013;transport=udp>
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Accept:application/sdp
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   c:application/sdp
   Content-Disposition:session
   l:1550
   Remote-Party-ID:"1004"<sip:1004 at 192.168.144.130>;party=calling;privacy=off;screen=no
   
   v=0
   o=FreeSWITCH 1434498598 1434498599 IN IP4 192.168.144.130
   s=FreeSWITCH
   c=IN IP4 192.168.144.130
   t=0 0
   a=msid-semantic: WMS cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   m=audio 30612 RTP/SAVPF 111 126
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 useinbandfec=1; minptime=10
   a=rtpmap:126 telephone-event/8000
   a=ptime:20
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:active
   a=rtcp-mux
   a=rtcp:30612 IN IP4 192.168.144.130
   a=ice-ufrag:NBzX7USM62b5UR7h
   a=ice-pwd:7gwWPgx66SYWrDW45lS26BF2
   a=candidate:7309048561 1 udp 659136 192.168.144.130 30612 typ host generation 0
   a=ssrc:2508539834 cname:wpipZITKqZv20IO9
   a=ssrc:2508539834 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc a0
   a=ssrc:2508539834 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   a=ssrc:2508539834 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pca0
   m=video 28128 RTP/SAVPF 100
   b=AS:1024
   a=rtpmap:100 VP8/90000
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:active
   a=rtcp-mux
   a=rtcp:28128 IN IP4 192.168.144.130
   a=rtcp-fb:100 ccm fir
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=ssrc:1791296093 cname:wpipZITKqZv20IO9
   a=ssrc:1791296093 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc v0
   a=ssrc:1791296093 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   a=ssrc:1791296093 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pcv0
   a=ice-ufrag:WuOzDUI2IaupUY58
   a=ice-pwd:dwo33dpSyZDos77erMEVwcN4
   a=candidate:5716597382 1 udp 659136 192.168.144.130 28128 typ host generation 0
   ------------------------------------------------------------------------
send 717 bytes to ws/[192.168.144.1]:22752 at 10:20:10.590509:
   ------------------------------------------------------------------------
   INFO sip:khn5qrri at v64e43th9nja.invalid;transport=ws;ob SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKeXj83mmeyHFQr
   Max-Forwards:70
   f:<sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   t:"1006"<sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   i:e46qgviqdnnt7tat85f0
   CSeq:76928541 INFO
   m:<sip:1004 at 84.242.71.194:52013;transport=udp>
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   c:application/media_control+xml
   l:149
   
   <?xml version="1.0" encoding="utf-8" ?>
   <media_control><vc_primitive><to_encoder><picture_fast_update /></to_encoder></vc_primitive></media_control>
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.606848 [DEBUG] sofia.c:6701 Channel sofia/internal/1006 at 192.168.144.130 entering state [early][183]
recv 291 bytes from ws/[192.168.144.1]:22752 at 10:20:10.621915:
   ------------------------------------------------------------------------
   SIP/2.0 403 Wrong Status
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKeXj83mmeyHFQr
   To: "1006"<sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   From: <sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 76928541 INFO
   Supported: outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.656904 [NOTICE] switch_rtp.c:1174 Auto Changing video stun/rtp/dtls port from 84.242.71.194:52392 to 192.168.144.1:52390
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.656904 [NOTICE] switch_rtp.c:1174 Auto Changing audio stun/rtp/dtls port from 84.242.71.194:52386 to 192.168.144.1:52384
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.666358 [INFO] switch_rtp.c:3006 Changing video DTLS state from HANDSHAKE to SETUP
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.666358 [INFO] switch_rtp.c:2915 video Fingerprint Verified.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.666358 [INFO] switch_rtp.c:3495 Activating Video Secure RTP SEND
2015-06-17 10:20:11.666358 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.666358 [INFO] switch_rtp.c:3473 Activating Video Secure RTP RECV
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.666358 [INFO] switch_rtp.c:2955 Changing video DTLS state from SETUP to READY
2015-06-17 10:20:11.666358 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.696561 [INFO] switch_rtp.c:3006 Changing audio DTLS state from HANDSHAKE to SETUP
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.696561 [INFO] switch_rtp.c:2915 audio Fingerprint Verified.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.696561 [INFO] switch_rtp.c:3495 Activating Audio Secure RTP SEND
2015-06-17 10:20:11.696561 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.696561 [INFO] switch_rtp.c:3473 Activating Audio Secure RTP RECV
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:11.696561 [INFO] switch_rtp.c:2955 Changing audio DTLS state from SETUP to READY
2015-06-17 10:20:11.696561 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
recv 3354 bytes from ws/[192.168.144.1]:22753 at 10:20:21.599953:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKDmSF2S3a18r4c
   To: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   From: "Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928541 INVITE
   Contact: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws>
   X-Can-Renegotiate: true
   Session-Expires: 90;refresher=uas
   Supported: timer,ice,outbound
   Content-Type: application/sdp
   Content-Length: 2831
   
   v=0
   o=- 2475930900982572858 2 IN IP4 127.0.0.1
   s=-
   t=0 0
   a=msid-semantic: WMS We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
   m=audio 52401 RTP/SAVPF 111 9 0 8 101
   c=IN IP4 84.242.71.194
   a=rtcp:9 IN IP4 0.0.0.0
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52399 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52400 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52401 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52401 typ srflx raddr 192.168.0.116 rport 52401 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:7pJzyXWNl2g2d9gG
   a=ice-pwd:f8EJCX3pYzimtTkfrm3wOFXk
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:active
   a=mid:audio
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 minptime=10; useinbandfec=1
   a=rtpmap:9 G722/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=maxptime:60
   a=ssrc:2747306552 cname:JP7obZ5nhBHyJmpU
   a=ssrc:2747306552 msid:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV c71d22d0-89ec-4af6-8578-254550f11a1f
   a=ssrc:2747306552 mslabel:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
   a=ssrc:2747306552 label:c71d22d0-89ec-4af6-8578-254550f11a1f
   m=video 52404 RTP/SAVPF 100
   c=IN IP4 84.242.71.194
   a=rtcp:9 IN IP4 0.0.0.0
   a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52402 typ host generation 0
   a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52403 typ host generation 0
   a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52404 typ host generation 0
   a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52404 typ srflx raddr 192.168.0.116 rport 52404 generation 0
   a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
   a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
   a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
   a=ice-ufrag:gN47dFpHilFs7c04
   a=ice-pwd:Ljd89i2PAzpurZpVICmEEP7F
   a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
   a=setup:active
   a=mid:video
   a=sendrecv
   a=rtcp-mux
   a=rtpmap:100 VP8/90000
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=ssrc:444017584 cname:JP7obZ5nhBHyJmpU
   a=ssrc:444017584 msid:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV 1d82754e-5cf4-4dd1-890d-c4e69e4441ac
   a=ssrc:444017584 mslabel:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
   a=ssrc:444017584 label:1d82754e-5cf4-4dd1-890d-c4e69e4441ac
   ------------------------------------------------------------------------
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] sofia.c:6701 Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid entering state [completing][200]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] sofia.c:6711 Remote SDP:
d75b158c-5a16-43c3-acb7-b956e42de156 v=0
d75b158c-5a16-43c3-acb7-b956e42de156 o=- 2475930900982572858 2 IN IP4 127.0.0.1
d75b158c-5a16-43c3-acb7-b956e42de156 s=-
d75b158c-5a16-43c3-acb7-b956e42de156 t=0 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=msid-semantic: WMS We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
d75b158c-5a16-43c3-acb7-b956e42de156 m=audio 52401 RTP/SAVPF 111 9 0 8 101
d75b158c-5a16-43c3-acb7-b956e42de156 c=IN IP4 84.242.71.194
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:111 opus/48000/2
d75b158c-5a16-43c3-acb7-b956e42de156 a=fmtp:111 minptime=10; useinbandfec=1
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:9 G722/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:0 PCMU/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:8 PCMA/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:101 telephone-event/8000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp:9 IN IP4 0.0.0.0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52399 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52400 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52401 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52401 typ srflx raddr 192.168.0.116 rport 52401 generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-ufrag:7pJzyXWNl2g2d9gG
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-pwd:f8EJCX3pYzimtTkfrm3wOFXk
d75b158c-5a16-43c3-acb7-b956e42de156 a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
d75b158c-5a16-43c3-acb7-b956e42de156 a=setup:active
d75b158c-5a16-43c3-acb7-b956e42de156 a=mid:audio
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-mux
d75b158c-5a16-43c3-acb7-b956e42de156 a=maxptime:60
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:2747306552 cname:JP7obZ5nhBHyJmpU
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:2747306552 msid:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV c71d22d0-89ec-4af6-8578-254550f11a1f
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:2747306552 mslabel:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:2747306552 label:c71d22d0-89ec-4af6-8578-254550f11a1f
d75b158c-5a16-43c3-acb7-b956e42de156 m=video 52404 RTP/SAVPF 100
d75b158c-5a16-43c3-acb7-b956e42de156 c=IN IP4 84.242.71.194
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtpmap:100 VP8/90000
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp:9 IN IP4 0.0.0.0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1202345197 1 udp 2122260223 192.168.144.1 52402 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1918330882 1 udp 2122194687 192.168.253.1 52403 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1728331434 1 udp 2122129151 192.168.0.116 52404 typ host generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:3863754782 1 udp 1685921535 84.242.71.194 52404 typ srflx raddr 192.168.0.116 rport 52404 generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:153724957 1 tcp 1518280447 192.168.144.1 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:1020564722 1 tcp 1518214911 192.168.253.1 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=candidate:696762970 1 tcp 1518149375 192.168.0.116 0 typ host tcptype active generation 0
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-ufrag:gN47dFpHilFs7c04
d75b158c-5a16-43c3-acb7-b956e42de156 a=ice-pwd:Ljd89i2PAzpurZpVICmEEP7F
d75b158c-5a16-43c3-acb7-b956e42de156 a=fingerprint:sha-256 1C:D2:DD:40:53:01:52:AE:1A:C0:37:F8:7B:9F:F3:E8:F2:4E:30:5C:AD:41:3F:85:40:7B:77:CD:D7:0D:62:64
d75b158c-5a16-43c3-acb7-b956e42de156 a=setup:active
d75b158c-5a16-43c3-acb7-b956e42de156 a=mid:video
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-mux
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-fb:100 nack
d75b158c-5a16-43c3-acb7-b956e42de156 a=rtcp-fb:100 nack pli
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:444017584 cname:JP7obZ5nhBHyJmpU
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:444017584 msid:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV 1d82754e-5cf4-4dd1-890d-c4e69e4441ac
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:444017584 mslabel:We62jBs0bTvH51E7453KMYJJuF0i9oXORpIV
d75b158c-5a16-43c3-acb7-b956e42de156 a=ssrc:444017584 label:1d82754e-5cf4-4dd1-890d-c4e69e4441ac
d75b158c-5a16-43c3-acb7-b956e42de156 
send 405 bytes to ws/[192.168.144.1]:22753 at 10:20:21.601417:
   ------------------------------------------------------------------------
   ACK sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKF6B15F5HUt59K
   Max-Forwards:70
   f:"Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   t:<sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   i:8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928541 ACK
   m:<sip:mod_sofia at 84.242.71.194:52013>
   l:0
   
   ------------------------------------------------------------------------
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] sofia.c:6701 Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid entering state [ready][200]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:2]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[G722:9:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMU:0:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMA:8:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:2]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[G722:9:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:2]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[G722:9:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:2]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[G722:9:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4117 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4062 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3978 Set telephone-event payload to 101
2015-06-17 10:20:21.596130 [DEBUG] mod_opus.c:341 Opus encoder set bitrate to local settings [-1000bps]
2015-06-17 10:20:21.596130 [DEBUG] mod_opus.c:341 Opus encoder set bitrate to local settings [-1000bps]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:2848 Set Codec sofia/internal/bbtn4jmc at f980t6k4le7l.invalid opus/48000 20 ms 960 samples 0 bits 1 channels
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_codec.c:111 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Original read codec set to opus:116
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [WARNING] switch_core_media.c:3184 NO candidate ACL defined, Defaulting to wan.auto
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.144.1:52399
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.253.1:52400
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.116:52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save audio Candidate cid: 1 proto: udp type: srflx addr: 84.242.71.194:52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3253 Searching for rtp candidate.
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3258 Choose rtp candidate, index 3, 84.242.71.194:52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3076 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid choosing family v4
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3269 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 84.242.71.194:52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3314 setting remote audio ice addr to index 3 84.242.71.194:52401 based on candidate
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3335 Setting remote rtcp audio addr to 84.242.71.194:52401 based on candidate
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4320 Set 2833 dtmf send payload to 101
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4475 Video Codec Compare [VP8:100]/[VP8:99]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:4487 Video Codec Compare [VP8:99] +++ is saved as a match
2015-06-17 10:20:21.596130 [DEBUG] mod_vpx.c:504 VPX VER:v1.4.0 VPX_IMAGE_ABI_VERSION:3 VPX_CODEC_ABI_VERSION:6
2015-06-17 10:20:21.596130 [DEBUG] mod_vpx.c:504 VPX VER:v1.4.0 VPX_IMAGE_ABI_VERSION:3 VPX_CODEC_ABI_VERSION:6
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:2664 Set VIDEO Codec sofia/internal/bbtn4jmc at f980t6k4le7l.invalid VP8/90000 0 ms
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [WARNING] switch_core_media.c:3184 NO candidate ACL defined, Defaulting to wan.auto
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.144.1:52402
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.253.1:52403
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.0.116:52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3213 Save video Candidate cid: 1 proto: udp type: srflx addr: 84.242.71.194:52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3253 Searching for rtp candidate.
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3258 Choose rtp candidate, index 3, 84.242.71.194:52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3076 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid choosing family v4
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3269 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 84.242.71.194:52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3314 setting remote video ice addr to index 3 84.242.71.194:52404 based on candidate
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:3335 Setting remote rtcp video addr to 84.242.71.194:52404 based on candidate
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:5885 AUDIO RTP [sofia/internal/bbtn4jmc at f980t6k4le7l.invalid] 192.168.144.130 port 32080 -> 84.242.71.194 port 52401 codec: 111 ms: 20
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_rtp.c:3682 Starting timer [soft] 960 bytes per 20ms
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [INFO] switch_core_media.c:6060 Activating Audio ICE
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [NOTICE] switch_rtp.c:4201 Activating RTP audio ICE: 7pJzyXWNl2g2d9gG:yhNwO9GwVJbouij1 84.242.71.194:52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [INFO] switch_core_media.c:6103 Activating RTCP PORT 52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_rtp.c:4098 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 52401
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [INFO] switch_core_media.c:6111 Skipping RTCP ICE (Same as RTP)
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [INFO] switch_rtp.c:3212 Activate RTP/RTCP audio DTLS server
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [INFO] switch_rtp.c:3332 Changing audio DTLS state from OFF to HANDSHAKE
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:6184 Set 2833 dtmf send payload to 101
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:6190 Set 2833 dtmf receive payload to 101
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_core_media.c:6212 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Set rtp dtmf delay to 40
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.596130 [DEBUG] switch_rtp.c:3693 Starting video timer.
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [DEBUG] switch_core_media.c:6401 VIDEO RTP [sofia/internal/bbtn4jmc at f980t6k4le7l.invalid] 192.168.144.130:27260->84.242.71.194:52404 codec: 100 ms: 0 [SUCCESS]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [DEBUG] switch_core_media.c:5063 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Starting Video thread
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [INFO] switch_core_media.c:6442 Activating Video ICE
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [NOTICE] switch_rtp.c:4201 Activating RTP video ICE: gN47dFpHilFs7c04:SpCSA7i6VRWi3BD4 84.242.71.194:52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [INFO] switch_core_media.c:6480 Activating VIDEO RTCP PORT 52404 interval 2000 mux 1
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [DEBUG] switch_rtp.c:4098 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 52404
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [INFO] switch_core_media.c:6491 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [INFO] switch_rtp.c:3212 Activate RTP/RTCP video DTLS server
2015-06-17 10:20:21.606120 [DEBUG] switch_core_media.c:4902 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Video thread started. Echo is off
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [INFO] switch_rtp.c:3332 Changing video DTLS state from OFF to HANDSHAKE
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [NOTICE] sofia.c:7562 Channel [sofia/internal/bbtn4jmc at f980t6k4le7l.invalid] has been answered
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.606120 [DEBUG] switch_channel.c:3759 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Callstate Change RINGING -> ACTIVE
send 782 bytes to ws/[192.168.144.1]:22753 at 10:20:21.608697:
   ------------------------------------------------------------------------
   INFO sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKgF5S7apNr3UvF
   Max-Forwards:70
   f:"Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   t:<sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   i:8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928542 INFO
   m:<sip:mod_sofia at 84.242.71.194:52013>
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   c:application/media_control+xml
   l:149
   
   <?xml version="1.0" encoding="utf-8" ?>
   <media_control><vc_primitive><to_encoder><picture_fast_update /></to_encoder></vc_primitive></media_control>
   ------------------------------------------------------------------------
recv 427 bytes from ws/[192.168.144.1]:22753 at 10:20:21.611364:
   ------------------------------------------------------------------------
   SIP/2.0 415 Unsupported Media Type
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKgF5S7apNr3UvF
   To: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   From: "Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928542 INFO
   Accept: application/sdp, application/dtmf-relay
   Supported: outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [DEBUG] switch_core_codec.c:246 sofia/internal/1006 at 192.168.144.130 Restore previous codec opus:116.
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [DEBUG] mod_sofia.c:798 Local SDP sofia/internal/1006 at 192.168.144.130:
09929802-6ea9-40f8-a875-3f6325bf3bde v=0
09929802-6ea9-40f8-a875-3f6325bf3bde o=FreeSWITCH 1434498598 1434498600 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde s=FreeSWITCH
09929802-6ea9-40f8-a875-3f6325bf3bde c=IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde t=0 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=msid-semantic: WMS cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde m=audio 30612 RTP/SAVPF 111 126
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:111 opus/48000/2
09929802-6ea9-40f8-a875-3f6325bf3bde a=fmtp:111 useinbandfec=1; minptime=10
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:126 telephone-event/8000
09929802-6ea9-40f8-a875-3f6325bf3bde a=ptime:20
09929802-6ea9-40f8-a875-3f6325bf3bde a=sendrecv
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:active
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:30612 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:NBzX7USM62b5UR7h
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:7gwWPgx66SYWrDW45lS26BF2
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:2363874976 1 udp 659136 192.168.144.130 30612 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 cname:wpipZITKqZv20IO9
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc a0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:2508539834 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pca0
09929802-6ea9-40f8-a875-3f6325bf3bde m=video 28128 RTP/SAVPF 100
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtpmap:100 VP8/90000
09929802-6ea9-40f8-a875-3f6325bf3bde a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
09929802-6ea9-40f8-a875-3f6325bf3bde a=setup:active
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-mux
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp:28128 IN IP4 192.168.144.130
09929802-6ea9-40f8-a875-3f6325bf3bde b=AS:1024
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 ccm fir
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack
09929802-6ea9-40f8-a875-3f6325bf3bde a=rtcp-fb:100 nack pli
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 cname:wpipZITKqZv20IO9
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc v0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
09929802-6ea9-40f8-a875-3f6325bf3bde a=ssrc:1791296093 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pcv0
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-ufrag:WuOzDUI2IaupUY58
09929802-6ea9-40f8-a875-3f6325bf3bde a=ice-pwd:dwo33dpSyZDos77erMEVwcN4
09929802-6ea9-40f8-a875-3f6325bf3bde a=candidate:5642761560 1 udp 659136 192.168.144.130 28128 typ host generation 0
09929802-6ea9-40f8-a875-3f6325bf3bde 
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [NOTICE] switch_ivr_originate.c:3522 Channel [sofia/internal/1006 at 192.168.144.130] has been answered
send 2379 bytes to ws/[192.168.144.1]:22752 at 10:20:21.624295:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   t:<sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1850 INVITE
   m:<sip:1004 at 84.242.71.194:52013;transport=udp>
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   u:talk,hold,conference,presence,as-feature-event,dialog,line-seize,call-info,sla,include-session-description,presence.winfo,message-summary,refer
   c:application/sdp
   Content-Disposition:session
   l:1550
   X-Can-Renegotiate:true
   Remote-Party-ID:"Outbound Call"<sip:bbtn4jmc at 192.168.144.130>;party=calling;privacy=off;screen=no
   
   v=0
   o=FreeSWITCH 1434498598 1434498599 IN IP4 192.168.144.130
   s=FreeSWITCH
   c=IN IP4 192.168.144.130
   t=0 0
   a=msid-semantic: WMS cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   m=audio 30612 RTP/SAVPF 111 126
   a=rtpmap:111 opus/48000/2
   a=fmtp:111 useinbandfec=1; minptime=10
   a=rtpmap:126 telephone-event/8000
   a=ptime:20
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:active
   a=rtcp-mux
   a=rtcp:30612 IN IP4 192.168.144.130
   a=ice-ufrag:NBzX7USM62b5UR7h
   a=ice-pwd:7gwWPgx66SYWrDW45lS26BF2
   a=candidate:7309048561 1 udp 659136 192.168.144.130 30612 typ host generation 0
   a=ssrc:2508539834 cname:wpipZITKqZv20IO9
   a=ssrc:2508539834 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc a0
   a=ssrc:2508539834 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   a=ssrc:2508539834 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pca0
   m=video 28128 RTP/SAVPF 100
   b=AS:1024
   a=rtpmap:100 VP8/90000
   a=fingerprint:sha-256 88:8D:EC:64:9C:B0:A3:D9:D5:74:2F:F5:24:14:DE:6E:12:A5:F8:62:53:24:4A:91:EE:28:92:CC:06:9E:9E:DF
   a=setup:active
   a=rtcp-mux
   a=rtcp:28128 IN IP4 192.168.144.130
   a=rtcp-fb:100 ccm fir
   a=rtcp-fb:100 nack
   a=rtcp-fb:100 nack pli
   a=ssrc:1791296093 cname:wpipZITKqZv20IO9
   a=ssrc:1791296093 msid:cEKg3KzDURfUManWifWAkFu4Pljy92Pc v0
   a=ssrc:1791296093 mslabel:cEKg3KzDURfUManWifWAkFu4Pljy92Pc
   a=ssrc:1791296093 label:cEKg3KzDURfUManWifWAkFu4Pljy92Pcv0
   a=ice-ufrag:WuOzDUI2IaupUY58
   a=ice-pwd:dwo33dpSyZDos77erMEVwcN4
   a=candidate:5716597382 1 udp 659136 192.168.144.130 28128 typ host generation 0
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [DEBUG] switch_channel.c:3759 (sofia/internal/1006 at 192.168.144.130) Callstate Change EARLY -> ACTIVE
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [DEBUG] sofia.c:6701 Channel sofia/internal/1006 at 192.168.144.130 entering state [completed][200]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.616158 [DEBUG] switch_ivr.c:195 Codec Activated L16 at 48000hz 1 channels 20ms
recv 407 bytes from ws/[192.168.144.1]:22752 at 10:20:21.642797:
   ------------------------------------------------------------------------
   ACK sip:1004 at 84.242.71.194:52013;transport=udp SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK3908570
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1850 ACK
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.626134 [DEBUG] sofia.c:6701 Channel sofia/internal/1006 at 192.168.144.130 entering state [ready][200]
recv 459 bytes from ws/[192.168.144.1]:22752 at 10:20:21.643686:
   ------------------------------------------------------------------------
   BYE sip:1004 at 84.242.71.194:52013;transport=udp SIP/2.0
   Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK3492319
   Max-Forwards: 69
   To: <sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1851 BYE
   Reason: SIP ;cause=488; text="Not Acceptable Here"
   Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
   Supported: outbound
   User-Agent: JsSIP 0.6.5
   Content-Length: 0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.626134 [NOTICE] sofia.c:952 Hangup sofia/internal/1006 at 192.168.144.130 [CS_EXECUTE] [NORMAL_CLEARING]
send 459 bytes to ws/[192.168.144.1]:22752 at 10:20:21.644976:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   v:SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK3492319;received=192.168.144.1;rport=22752
   From: "1006" <sip:1006 at 192.168.144.130>;tag=flk0cbpd3o
   To: <sip:1004 at 192.168.144.130>;tag=mKHK186Bm12va
   Call-ID: e46qgviqdnnt7tat85f0
   CSeq: 1851 BYE
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   l:0
   
   ------------------------------------------------------------------------
2015-06-17 10:20:21.646158 [DEBUG] switch_core_media.c:5030 sofia/internal/1006 at 192.168.144.130 Video thread ended
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_ivr_originate.c:3723 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [NOTICE] switch_ivr_originate.c:3812 Hangup sofia/internal/bbtn4jmc at f980t6k4le7l.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_HANGUP
2015-06-17 10:20:21.686171 [DEBUG] switch_core_media.c:5030 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Video thread ended
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Callstate Change ACTIVE -> HANGUP
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State HANGUP
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:425 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Overriding SIP cause 487 with 200 from the other leg
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [NOTICE] switch_ivr_originate.c:2735 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:431 Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid hanging up, cause: ORIGINATOR_CANCEL
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_ivr_originate.c:3723 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [INFO] mod_dptools.c:3303 Originate Failed.  Cause: ORIGINATOR_CANCEL
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_session.c:2924 sofia/internal/1006 at 192.168.144.130 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1006 at 192.168.144.130) State EXECUTE going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_HANGUP
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1006 at 192.168.144.130) Callstate Change ACTIVE -> HANGUP
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:483 Sending BYE to sofia/internal/bbtn4jmc at f980t6k4le7l.invalid
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:60 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Standard HANGUP, cause: ORIGINATOR_CANCEL
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State HANGUP going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1006 at 192.168.144.130) State HANGUP
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_media.c:10244 sofia/internal/1006 at 192.168.144.130 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1006 at 192.168.144.130 hanging up, cause: NORMAL_CLEARING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State Change CS_HANGUP -> CS_REPORTING
send 606 bytes to ws/[192.168.144.1]:22753 at 10:20:21.688839:
   ------------------------------------------------------------------------
   BYE sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws SIP/2.0
   v:SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKHryj956rNcjFB
   Max-Forwards:70
   f:"Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   t:<sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_REPORTING
   i:8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq:76928543 BYE
   User-Agent:FreeSWITCH-mod_sofia/1.7.0+git~20150617T032405Z~e0edc956d8~64bit
   Allow:INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY,PUBLISH,SUBSCRIBE
   k:path,replaces
   Reason:SIP;cause=488;text="Not Acceptable Here"
   l:0
   
   ------------------------------------------------------------------------
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1006 at 192.168.144.130 Standard HANGUP, cause: NORMAL_CLEARING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1006 at 192.168.144.130) State HANGUP going to sleep
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State REPORTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:104 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Standard REPORTING, cause: ORIGINATOR_CANCEL
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State REPORTING going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1006 at 192.168.144.130) State Change CS_HANGUP -> CS_REPORTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_REPORTING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1006 at 192.168.144.130) State REPORTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State Change CS_REPORTING -> CS_DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1006 at 192.168.144.130 Standard REPORTING, cause: NORMAL_CLEARING
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1006 at 192.168.144.130) State REPORTING going to sleep
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_session.c:1639 Session 2 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Locked, Waiting on external entities
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [NOTICE] switch_core_session.c:1657 Session 2 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Ended
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/bbtn4jmc at f980t6k4le7l.invalid [CS_DESTROY]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) Running State Change CS_DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1006 at 192.168.144.130) State Change CS_REPORTING -> CS_DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_session.c:1639 Session 1 (sofia/internal/1006 at 192.168.144.130) Locked, Waiting on external entities
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [NOTICE] switch_core_session.c:1657 Session 1 (sofia/internal/1006 at 192.168.144.130) Ended
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [NOTICE] switch_core_session.c:1661 Close Channel sofia/internal/1006 at 192.168.144.130 [CS_DESTROY]
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State DESTROY
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:341 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid SOFIA DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1006 at 192.168.144.130) Running State Change CS_DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1006 at 192.168.144.130) State DESTROY
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:111 sofia/internal/bbtn4jmc at f980t6k4le7l.invalid Standard DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:341 sofia/internal/1006 at 192.168.144.130 SOFIA DESTROY
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/bbtn4jmc at f980t6k4le7l.invalid) State DESTROY going to sleep
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1006 at 192.168.144.130 Standard DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1006 at 192.168.144.130) State DESTROY going to sleep
recv 357 bytes from ws/[192.168.144.1]:22753 at 10:20:21.693414:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/WS 192.168.144.130:5066;branch=z9hG4bKHryj956rNcjFB
   To: <sip:bbtn4jmc at f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
   From: "Extension 1006"<sip:1006 at 192.168.144.130>;tag=Nvac33QFHaSFp
   Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
   CSeq: 76928543 BYE
   Supported: outbound
   Content-Length: 0
   
   ------------------------------------------------------------------------



-- 
S pozdravem,
Ing.Denis Jakovlev                           
mob.tel. 775-415-382
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150617/8590aba8/attachment-0001.html 


Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list