[Freeswitch-users] The problem with internal calls.

Michael Jerris mike at jerris.com
Thu Jun 18 19:49:46 MSD 2015


A bunch of people now who keep insisting on using jssip have failed because it DOES NOT WORK AND HAS KNOWN BUGS.  using Sip.js, which has almost identical api and a migration guide has fixed every single one of them.  JSSIP does not work, and hasn't worked for anyone without hacking it up, sip.js does.


> On Jun 18, 2015, at 11:31 AM, Sharath Kumar <Sharath.Kumar at meZocliq.com> wrote:
> 
> I believe it is due to this
> 
>  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 <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 <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
> 
> Freeswitch is indicating support for either sdp or dtmf-relay,while the INFO from the jssip uses 
> c:application/media_control+xml and sends an XML payload.
> 
> If you don't need Video and only audio turn it off in Jssip and you should see audio working.
> 
> thanks
> Sharath 
> From: freeswitch-users-bounces at lists.freeswitch.org <freeswitch-users-bounces at lists.freeswitch.org> on behalf of Denis Jakovlev <yadenis at seznam.cz>
> Sent: Wednesday, June 17, 2015 5:20 AM
> To: FreeSWITCH Users Help
> Subject: [Freeswitch-users] The problem with internal calls.
>  
> 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
>  <http://www.freeswitch.org/>_________________________________________________________________________
> Professional FreeSWITCH Consulting Services: 
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
> 
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
> 
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150618/7134f8f6/attachment-0001.html 


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