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