Re: [Freeswitch-users] The problem with internal calls.

18 Jun 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@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753 <sip:bbtn4jmc@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>>;tag=20jkpd0c78
  From: "Extension 1006"<sip:1006@192.168.144.130 <sip:1006@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@lists.freeswitch.org <freeswitch-users-bounces@lists.freeswitch.org> on behalf of Denis Jakovlev <yadenis@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@freeswitch.org       |
|   Brought to you by ClueCon http://www.cluecon.com/         |
.=============================================================.
.=======================================================================================================.
|    ____ _             ____                                                                            |
|   / ___| |_   _  ___ / ___|___  _ __                                                                  |
|  | |   | | | | |/ _ \ |   / _ \| '_ \                                                                 |
|  | |___| | |_| |  __/ |__| (_) | | | |                                                                |
|   \____|_|\__,_|\___|\____\___/|_| |_|                                                                |
|                                                                                                       |
|   _____    _            _                          ____             __                                |
|  |_   _|__| | ___ _ __ | |__   ___  _ __  _   _   / ___|___  _ __  / _| ___ _ __ ___ _ __   ___ ___   |
|    | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | |   / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \  |
|    | |  __/ |  __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | |  _|  __/ | |  __/ | | | (_|  __/  |
|    |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, |  \____\___/|_| |_|_|  \___|_|  \___|_| |_|\___\___|  |
|                  |_|                      |___/                                                       |
|   _____                           _                         _                                         |
|  | ____|_   _____ _ __ _   _     / \  _   _  __ _ _   _ ___| |_                                       |
|  |  _| \ \ / / _ \ '__| | | |   / _ \| | | |/ _` | | | / __| __|                                      |
|  | |___ \ V /  __/ |  | |_| |  / ___ \ |_| | (_| | |_| \__ \ |_                                       |
|  |_____| \_/ \___|_|   \__, | /_/   \_\__,_|\__, |\__,_|___/\__|                                      |
|                        |___/                |___/                                                     |
|                                       ____ _             ____                                         |
|  __      ____      ____      __      / ___| |_   _  ___ / ___|___  _ __         ___ ___  _ __ ___     |
|  \ \ /\ / /\ \ /\ / /\ \ /\ / /     | |   | | | | |/ _ \ |   / _ \| '_ \       / __/ _ \| '_ ` _ \    |
|   \ V  V /  \ V  V /  \ V  V /   _  | |___| | |_| |  __/ |__| (_) | | | |  _  | (_| (_) | | | | | |   |
|    \_/\_/    \_/\_/    \_/\_/   (_)  \____|_|\__,_|\___|\____\___/|_| |_| (_)  \___\___/|_| |_| |_|   |
|                                                                                                       |
.=======================================================================================================.
[m
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@192.168.144.130>
  From: "1006" <sip:1006@192.168.144.130>;tag=nomjh3h6ae
  Call-ID: 0ttgsska8pti930806otjf
  CSeq: 6 REGISTER
  Contact: <sip:khn5qrri@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@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@192.168.144.130>;tag=nomjh3h6ae
  t:<sip:1006@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@192.168.144.130>
  From: "1006" <sip:1006@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@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@192.168.144.130]
Contact: ["" <sip:khn5qrri@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@192.168.144.130>;tag=nomjh3h6ae
  t:<sip:1006@192.168.144.130>;tag=eXSyp61p5BXcN
  Call-ID: 0ttgsska8pti930806otjf
  CSeq: 7 REGISTER
  m:<sip:khn5qrri@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@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@192.168.144.1:22752>;transport=ws
  Max-Forwards:70
  f:sip:1006@192.168.144.130;tag=F6jQr1jt2mKZg
  t:sip:1006@192.168.144.130
  i:7a42b99e-8f6c-1233-a4b7-000c296e07d9
  CSeq:76928533 NOTIFY
  m:sip:mod_sofia@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@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@192.168.144.130;tag=r4kchioi0g
  From: sip:1006@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@192.168.144.130>
  From: "1004" <sip:1004@192.168.144.130>;tag=r2glgg5k5q
  Call-ID: m97hiai4f15tf6n7grvop9
  CSeq: 17 REGISTER
  Contact: <sip:bbtn4jmc@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@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@192.168.144.130>;tag=r2glgg5k5q
  t:<sip:1004@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@192.168.144.130>
  From: "1004" <sip:1004@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@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@192.168.144.130]
Contact: ["" <sip:bbtn4jmc@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@192.168.144.130>;tag=r2glgg5k5q
  t:<sip:1004@192.168.144.130>;tag=Hr58UQm1v6Z4Q
  Call-ID: m97hiai4f15tf6n7grvop9
  CSeq: 18 REGISTER
  m:<sip:bbtn4jmc@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@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@192.168.144.1:22753>;transport=ws
  Max-Forwards:70
  f:sip:1004@192.168.144.130;tag=j1y1Xj54SFpQK
  t:sip:1004@192.168.144.130
  i:7c4a98a5-8f6c-1233-a4b7-000c296e07d9
  CSeq:76928535 NOTIFY
  m:sip:mod_sofia@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@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@192.168.144.130;tag=gt9rv3dg70
  From: sip:1004@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@192.168.144.130 SIP/2.0
  Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768
  Max-Forwards: 69
  To: <sip:1004@192.168.144.130>
  From: "1006" <sip:1006@192.168.144.130>;tag=flk0cbpd3o
  Call-ID: e46qgviqdnnt7tat85f0
  CSeq: 1849 INVITE
  Contact: <sip:khn5qrri@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@192.168.144.130>;tag=flk0cbpd3o
  To: <sip:1004@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@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@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@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@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@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@192.168.144.130>;tag=flk0cbpd3o
  t:<sip:1004@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@192.168.144.130 SIP/2.0
  Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK6832768
  To: <sip:1004@192.168.144.130>;tag=KartZDp8prcaF
  From: "1006" <sip:1006@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@192.168.144.130 SIP/2.0
  Via: SIP/2.0/WS v64e43th9nja.invalid;branch=z9hG4bK9223282
  Max-Forwards: 69
  To: <sip:1004@192.168.144.130>
  From: "1006" <sip:1006@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@192.168.144.130", response="2adf5b3f978933cac74d61632c740ba1", qop=auth, cnonce="fo5o7elbccv0", nc=00000001
  Contact: <sip:khn5qrri@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@192.168.144.130>;tag=flk0cbpd3o
  To: <sip:1004@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@192.168.144.130 parsing [default->unloop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->tod_example] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Date/Time Match (PASS) [tod_example] break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action set(open=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->holiday_example] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Date/TimeMatch (FAIL) [holiday_example] break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->global-intercept] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [global-intercept] destination_number(1004) =~ /^886$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->group-intercept] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [group-intercept] destination_number(1004) =~ /^\*8$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->intercept-ext] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [intercept-ext] destination_number(1004) =~ /^\*\*(\d+)$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->redial] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [redial] destination_number(1004) =~ /^(redial|870)$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->global] continue=true
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (PASS) [global] ${call_debug}(true) =~ /^true$/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action info()
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [global] ${default_password}(19551004) =~ /^1234$/ break=never
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@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@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@192.168.144.130 Absolute Condition [global]
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 Action hash(insert/${domain_name}-last_dial/global/${uuid})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->snom-demo-2] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->snom-demo-1] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->eavesdrop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->eavesdrop] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Regex (FAIL) [eavesdrop] destination_number(1004) =~ /^779$/ break=on-false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 parsing [default->call_return] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->del-group] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->add-group] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->call-group-simo] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->call-group-order] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->extension-intercom] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 parsing [default->Local_Extension] continue=false
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@192.168.144.130 Action export(dialed_extension=1004)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@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@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@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@192.168.144.130 Action set(ringback=${us-ring})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action set(transfer_ringback=local_stream://moh)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action set(call_timeout=30)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action set(hangup_after_bridge=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action set(uuid_bridge_continue_on_cancel=true)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action bridge({ignore_early_media=true}user/${dialed_extension}@${domain_name})
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action answer()
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@192.168.144.130 Action sleep(1000)
09929802-6ea9-40f8-a875-3f6325bf3bde Dialplan: sofia/internal/1006@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@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@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@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@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@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@192.168.144.130 Standard EXECUTE
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@192.168.144.130 SET [open]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@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@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@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@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@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@48000h@20i@2c,mod_spandsp.G722@8000h@20i@64000b,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@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@192.168.144.130>;tag=flk0cbpd3o]
09929802-6ea9-40f8-a875-3f6325bf3bde variable_sip_full_to: [<sip:1004@192.168.144.130>]
variable_sip_req_user: [1004]
variable_sip_req_uri: [1004@192.168.144.130]
variable_sip_req_host: [192.168.144.130]
variable_sip_to_user: [1004]
variable_sip_to_uri: [1004@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@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@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@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@192.168.144.130 hash(insert/192.168.144.130-last_dial/1006/1004)
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@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@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@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@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@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@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@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@192.168.144.130 SET [ringback]=[%(2000,4000,440,480)]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@192.168.144.130 SET [transfer_ringback]=[local_stream://moh]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@192.168.144.130 SET [call_timeout]=[30]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@192.168.144.130 SET [hangup_after_bridge]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@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@192.168.144.130 SET [uuid_bridge_continue_on_cancel]=[true]
09929802-6ea9-40f8-a875-3f6325bf3bde EXECUTE sofia/internal/1006@192.168.144.130 bridge({ignore_early_media=true}user/1004@192.168.144.130)
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.545814 [DEBUG] switch_channel.c:1235 sofia/internal/1006@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@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@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@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@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@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@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@f980t6k4le7l.invalid) State INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:88 sofia/internal/bbtn4jmc@f980t6k4le7l.invalid SOFIA INIT
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] sofia_glue.c:1223 sip:bbtn4jmc@192.168.144.1:22753;transport=ws Setting proxy route to sofia/internal/bbtn4jmc@f980t6k4le7l.invalid
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] sofia_glue.c:1252 sofia/internal/bbtn4jmc@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@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@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@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@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@f980t6k4le7l.invalid) State ROUTING
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.545814 [DEBUG] mod_sofia.c:141 sofia/internal/bbtn4jmc@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@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@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@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@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@192.168.144.1:22753>;transport=ws
  Max-Forwards:68
  f:"Extension 1006"<sip:1006@192.168.144.130>;tag=Nvac33QFHaSFp
  t:<sip:bbtn4jmc@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>
  i:8306c7c5-8f6c-1233-a4b7-000c296e07d9
  CSeq:76928541 INVITE
  m:<sip:mod_sofia@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@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@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@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@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>
  From: "Extension 1006"<sip:1006@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
  From: "Extension 1006"<sip:1006@192.168.144.130>;tag=Nvac33QFHaSFp
  Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
  CSeq: 76928541 INVITE
  Contact: <sip:bbtn4jmc@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@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@f980t6k4le7l.invalid!
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:10.565818 [DEBUG] switch_channel.c:3332 (sofia/internal/bbtn4jmc@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@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@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@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@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@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@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@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@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@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@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@192.168.144.130!
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:10.585823 [DEBUG] switch_channel.c:3460 (sofia/internal/1006@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@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@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@192.168.144.130>;tag=flk0cbpd3o
  t:<sip:1004@192.168.144.130>;tag=mKHK186Bm12va
  Call-ID: e46qgviqdnnt7tat85f0
  CSeq: 1850 INVITE
  m:<sip:1004@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@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@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@192.168.144.130>;tag=mKHK186Bm12va
  t:"1006"<sip:1006@192.168.144.130>;tag=flk0cbpd3o
  i:e46qgviqdnnt7tat85f0
  CSeq:76928541 INFO
  m:<sip:1004@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@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@192.168.144.130>;tag=flk0cbpd3o
  From: <sip:1004@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
  From: "Extension 1006"<sip:1006@192.168.144.130>;tag=Nvac33QFHaSFp
  Call-ID: 8306c7c5-8f6c-1233-a4b7-000c296e07d9
  CSeq: 76928541 INVITE
  Contact: <sip:bbtn4jmc@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@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@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@192.168.144.130>;tag=Nvac33QFHaSFp
  t:<sip:bbtn4jmc@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@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@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@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@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@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@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@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@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@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@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@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@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@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@f980t6k4le7l.invalid) Callstate Change RINGING -> ACTIVE
send 782 bytes to ws/[192.168.144.1]:22753 at 10:20:21.608697:
  ------------------------------------------------------------------------
  INFO sip:bbtn4jmc@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@192.168.144.130>;tag=Nvac33QFHaSFp
  t:<sip:bbtn4jmc@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@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
  From: "Extension 1006"<sip:1006@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@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@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@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@192.168.144.130>;tag=flk0cbpd3o
  t:<sip:1004@192.168.144.130>;tag=mKHK186Bm12va
  Call-ID: e46qgviqdnnt7tat85f0
  CSeq: 1850 INVITE
  m:<sip:1004@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@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@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@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@48000hz 1 channels 20ms
recv 407 bytes from ws/[192.168.144.1]:22752 at 10:20:21.642797:
  ------------------------------------------------------------------------
  ACK sip:1004@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@192.168.144.130>;tag=mKHK186Bm12va
  From: "1006" <sip:1006@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@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@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@192.168.144.130>;tag=mKHK186Bm12va
  From: "1006" <sip:1006@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@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@192.168.144.130>;tag=flk0cbpd3o
  To: <sip:1004@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@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@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@f980t6k4le7l.invalid) Running State Change CS_HANGUP
2015-06-17 10:20:21.686171 [DEBUG] switch_core_media.c:5030 sofia/internal/bbtn4jmc@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@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@f980t6k4le7l.invalid) State HANGUP
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:425 sofia/internal/bbtn4jmc@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@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@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@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@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@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@f980t6k4le7l.invalid
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] switch_core_state_machine.c:60 sofia/internal/bbtn4jmc@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@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@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@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@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@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@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@192.168.144.130>;tag=Nvac33QFHaSFp
  t:<sip:bbtn4jmc@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@f980t6k4le7l.invalid) State DESTROY
d75b158c-5a16-43c3-acb7-b956e42de156 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:341 sofia/internal/bbtn4jmc@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@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@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@f980t6k4le7l.invalid Standard DESTROY
09929802-6ea9-40f8-a875-3f6325bf3bde 2015-06-17 10:20:21.686171 [DEBUG] mod_sofia.c:341 sofia/internal/1006@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@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@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@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@f980t6k4le7l.invalid;transport=ws;received=192.168.144.1:22753>;tag=20jkpd0c78
  From: "Extension 1006"<sip:1006@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@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@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org