[Freeswitch-dev] SIPml5--Kamailio--FS 1.5 -- getting 'ice not ready' message and no audio when join to conf.

28 Apr 2014

      Hi all,

I am using SIPml5 client and Kamailio server integrated with FS 1.5 (
behind NAT box ), according to this tutorial:
http://kb.asipto.com/freeswitch:kamailio-3.3.x-freeswitch-1.2.x-sbc  (
let's say setup A )

Also, I have another setup at the same location, FS 1.5 only (setup B)

Joining to the conference from some remote location to setup B is always OK
-  I am getting two-way-audio.

The issue is: when I try to join to the conference from the same remote
location to setup A,  I have no audio.  From some other location, there is
no issue even with setup A, I can get two-way-audio.

In the non-working case, I see 'ice not ready' alert message in FS logs.

I compared both working and non-working FS traces and couldn't figure out
what is wrong.

Can someone take a look and give some guidance what I am missing here ?

Here is the FS log for non- working case (setup A - SIPml5 client is
registered to Kamailio server which is integrated with FS as media bridge ):

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_channel.c:1053 New Channel
sofia/internal/1001@vortex01.no-ip.info[6e61a7b2-ce59-11e3-bc32-179817d66480]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_NEW

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
sofia.c:8264 sofia/internal/1001@vortex01.no-ip.info receiving invite from
192.168.1.103:5060 version: 1.5.11b git 048ce8d 2014-03-15 03:13:34Z 64bit

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[received][100]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
sofia.c:6173 Remote SDP:

6e61a7b2-ce59-11e3-bc32-179817d66480 v=0

6e61a7b2-ce59-11e3-bc32-179817d66480 o=- 6933551414148352000 2 IN IP4
127.0.0.1

6e61a7b2-ce59-11e3-bc32-179817d66480 s=Doubango Telecom - chrome

6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=group:BUNDLE audio

6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS
NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo

6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 57265 RTP/SAVPF 111 103 104 0
8 106 105 13 126

6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 95.180.32.20

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:111 opus/48000/2

6e61a7b2-ce59-11e3-bc32-179817d66480 a=fmtp:111 minptime=10

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:103 ISAC/16000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:104 ISAC/32000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:8 PCMA/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/32000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:105 CN/16000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:13 CN/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:57265 IN IP4 95.180.32.20

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 1 udp
2113937151 192.168.0.100 57265 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 2 udp
2113937151 192.168.0.100 57265 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 1 udp
1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265
generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 2 udp
1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265
generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 1 tcp 1509957375
192.168.0.100 0 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 2 tcp 1509957375
192.168.0.100 0 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:gHnLryRtXCOYse+I

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:gD1S2kW13thhaaqe30SyLfi3

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-options:google-ice

6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256
39:84:0F:18:1B:DA:DB:39:D4:EB:80:4D:4C:4B:3A:DB:0B:A1:B9:7C:4B:2C:C9:00:4F:A3:9A:F9:66:C3:70:F4

6e61a7b2-ce59-11e3-bc32-179817d66480 a=setup:actpass

6e61a7b2-ce59-11e3-bc32-179817d66480 a=mid:audio

6e61a7b2-ce59-11e3-bc32-179817d66480 a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux

6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32
inline:Cz/8x3EVJwmDLmLlp5EbgpSVH4sK52PZjT9uA9E5

6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:GqldZ39QV90ToD8lVZVcimBM+Qf422rD4FlZK0nV

6e61a7b2-ce59-11e3-bc32-179817d66480 a=maxptime:60

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
cname:S5DliNJMaHoIOHFf

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
msid:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo
bceee12b-94e6-49e5-acab-bb334f96e7c6

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
mslabel:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
label:bceee12b-94e6-49e5-acab-bb334f96e7c6

6e61a7b2-ce59-11e3-bc32-179817d66480

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
sofia.c:6428 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_NEW
-> CS_INIT

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:486 (sofia/internal/1001@vortex01.no-ip.info)
State NEW

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_INIT

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info)
State INIT

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:87 sofia/internal/1001@vortex01.no-ip.info SOFIA INIT

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:40
sofia/internal/1001@vortex01.no-ip.infoStandard INIT

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:48 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_INIT -> CS_ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info)
State INIT going to sleep

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_channel.c:2177 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change DOWN -> RINGING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:164
sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context public

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->unloop] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~
/^true$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~
/^true$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->outside_call] continue=true

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Absolute Condition [outside_call]

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action set(outside_call=true)

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y
%T %z)})

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->call_debug] continue=true

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~
/^true$/ break=never

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->public_extensions] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [public_extensions]
destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->from_kamailio] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [from_kamailio]
network_addr(192.168.1.103) =~ /^192\.168\.1\.103$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [from_kamailio]
destination_number(3000) =~ /^(.+)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action transfer(3000 XML default)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_ROUTING -> CS_EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING going to sleep

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:256
sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info set(outside_call=true)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET
[outside_call]=[true]

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:15:25
+0200)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014
00:15:25 +0200]

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info transfer(3000 XML default)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_ivr.c:1831 (sofia/internal/1001@vortex01.no-ip.info) State Change
CS_EXECUTE -> CS_ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_ivr.c:1838 Transfer sofia/internal/1001@vortex01.no-ip.info to
XML[3000@default]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE going to sleep

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:164
sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context default

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->unloop] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~
/^true$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~
/^true$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->tod_example] continue=true

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->holiday_example] continue=true

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example]
break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->global-intercept] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [global-intercept]
destination_number(3000) =~ /^886$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group-intercept] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group-intercept]
destination_number(3000) =~ /^\*8$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->intercept-ext] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [intercept-ext]
destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->redial] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~
/^(redial|870)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->global] continue=true

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~
/^true$/ break=never

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info 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

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [global]
${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/
break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [global] ${switch_r_sdp}(v=0

6e61a7b2-ce59-11e3-bc32-179817d66480 o=- 6933551414148352000 2 IN IP4
127.0.0.1

6e61a7b2-ce59-11e3-bc32-179817d66480 s=Doubango Telecom - chrome

6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=group:BUNDLE audio

6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS
NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo

6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 57265 RTP/SAVPF 111 103 104 0
8 106 105 13 126

6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 95.180.32.20

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:111 opus/48000/2

6e61a7b2-ce59-11e3-bc32-179817d66480 a=fmtp:111 minptime=10

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:103 ISAC/16000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:104 ISAC/32000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:8 PCMA/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/32000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:105 CN/16000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:13 CN/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:57265 IN IP4 95.180.32.20

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 1 udp
2113937151 192.168.0.100 57265 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 2 udp
2113937151 192.168.0.100 57265 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 1 udp
1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265
generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 2 udp
1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265
generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 1 tcp 1509957375
192.168.0.100 0 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 2 tcp 1509957375
192.168.0.100 0 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:gHnLryRtXCOYse+I

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:gD1S2kW13thhaaqe30SyLfi3

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-options:google-ice

6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256
39:84:0F:18:1B:DA:DB:39:D4:EB:80:4D:4C:4B:3A:DB:0B:A1:B9:7C:4B:2C:C9:00:4F:A3:9A:F9:66:C3:70:F4

6e61a7b2-ce59-11e3-bc32-179817d66480 a=setup:actpass

6e61a7b2-ce59-11e3-bc32-179817d66480 a=mid:audio

6e61a7b2-ce59-11e3-bc32-179817d66480 a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux

6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32
inline:Cz/8x3EVJwmDLmLlp5EbgpSVH4sK52PZjT9uA9E5

6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:GqldZ39QV90ToD8lVZVcimBM+Qf422rD4FlZK0nV

6e61a7b2-ce59-11e3-bc32-179817d66480 a=maxptime:60

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
cname:S5DliNJMaHoIOHFf

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
msid:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo
bceee12b-94e6-49e5-acab-bb334f96e7c6

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
mslabel:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369
label:bceee12b-94e6-49e5-acab-bb334f96e7c6

6e61a7b2-ce59-11e3-bc32-179817d66480 ) =~
/(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action set(rtp_secure_media=true)

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Absolute Condition [global]

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-last_dial/global/${uuid})

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y
%T %z)})

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->snom-demo-2] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-2]
destination_number(3000) =~ /^9001$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->snom-demo-1] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-1]
destination_number(3000) =~ /^9000$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000)
=~ /^88(\d{4})$|^\*0(.*)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000)
=~ /^779$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call_return] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call_return]
destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->del-group] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [del-group] destination_number(3000)
=~ /^80(\d{2})$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->add-group] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [add-group] destination_number(3000)
=~ /^81(\d{2})$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call-group-simo] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call-group-simo]
destination_number(3000) =~ /^82(\d{2})$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call-group-order] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call-group-order]
destination_number(3000) =~ /^83(\d{2})$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->extension-intercom]
continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [extension-intercom]
destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->Local_Extension] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension]
destination_number(3000) =~ /^(10[01][0-9]|1500)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->Local_Extension_Skinny]
continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension_Skinny]
destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_sales] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_sales]
destination_number(3000) =~ /^2000$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_support]
continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_support]
destination_number(3000) =~ /^2001$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_billing]
continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_billing]
destination_number(3000) =~ /^2002$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->operator] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000)
=~ /^(operator|0)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->vmain] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [vmain] destination_number(3000) =~
/^vmain$|^4000$|^\*98$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->sip_uri] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~
/^sip:(.*)$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->nb_conferences] continue=false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [nb_conferences]
destination_number(3000) =~ /^(30\d{2})$/ break=on-false

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action answer()

6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action conference(3000-${domain_name}@default)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_ROUTING -> CS_EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING going to sleep

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_state_machine.c:256
sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info set(rtp_secure_media=true)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET
[rtp_secure_media]=[true]

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.infohash(insert/192.168.1.103-spymap/1001/6e61a7b2-ce59-11e3-bc32-179817d66480)

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info hash(insert/192.168.1.103-last_dial/1001/3000)

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.infohash(insert/192.168.1.103-last_dial/global/6e61a7b2-ce59-11e3-bc32-179817d66480)

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:15:25
+0200)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014
00:15:25 +0200]

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info answer()

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3439 Audio Codec Compare [PCMU:0:8000:20:64000] ++++ is
saved as a match

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3439 Audio Codec Compare [PCMA:8:8000:20:64000] ++++ is
saved as a match

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[G722:9:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[PCMU:0:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[PCMA:8:8000:20:64000]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[GSM:3:8000:20:13200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3311 Set telephone-event payload to 126

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:2343 Set Codec
sofia/internal/1001@vortex01.no-ip.infoPCMU/8000 20 ms 160 samples
64000 bits

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_codec.c:111 sofia/internal/1001@vortex01.no-ip.info Original
read codec set to PCMU:0

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [WARNING]
switch_core_media.c:2544 NO candidate ACL defined, Defaulting to wan.auto

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host
addr: 192.168.0.100:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: host
addr: 192.168.0.100:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host
addr: 192.168.0.100:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: host
addr: 192.168.0.100:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: srflx
addr: 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2577 Choose audio Candidate cid: 1 proto: udp type:
srflx addr: 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: srflx
addr: 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2577 Choose audio Candidate cid: 2 proto: udp type:
srflx addr: 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2706 setting remote audio ice addr to
95.180.32.20:57265based on candidate

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_core_media.c:2726 setting remote rtcp audio addr to
95.180.32.20:57265 based on candidate

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:3628 Set 2833 dtmf send/recv payload to 126

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:4843 AUDIO RTP [sofia/internal/1001@vortex01.no-ip.info]
192.168.1.103 port 18598 -> 95.180.32.20 port 57265 codec: 0 ms: 20

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_rtp.c:3334 Starting timer [soft] 160 bytes per 20ms

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
switch_core_media.c:5012 Activating Audio ICE

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
switch_rtp.c:3775 Activating RTP audio ICE:
gHnLryRtXCOYse+I:fFrH85qVZn46sq5j 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
switch_core_media.c:5055 Activating RTCP PORT 57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_rtp.c:3680 RTCP send rate is: 10000 and packet rate is: 20000 Remote
Port: 57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_rtp.c:2153 Setting RTCP remote addr to 95.180.32.20:57265

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
switch_core_media.c:5063 Skipping RTCP ICE (Same as RTP)

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO]
switch_rtp.c:2878 Activate RTP/RTCP audio DTLS client

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:5187 Set 2833 dtmf send payload to 126

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:5193 Set 2833 dtmf receive payload to 126

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_media.c:5221 Set comfort noise payload to 106

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
sofia_media.c:92 Pre-Answer sofia/internal/1001@vortex01.no-ip.info!

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_channel.c:3393 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change RINGING -> EARLY

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_sofia.c:775 Local SDP sofia/internal/1001@vortex01.no-ip.info:

6e61a7b2-ce59-11e3-bc32-179817d66480 v=0

6e61a7b2-ce59-11e3-bc32-179817d66480 o=FreeSWITCH 1398618327 1398618328 IN
IP4 192.168.1.103

6e61a7b2-ce59-11e3-bc32-179817d66480 s=FreeSWITCH

6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 192.168.1.103

6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS
3W5SBZTwGbY3LccBFF9pBCOTX46igew9

6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 18598 RTP/SAVPF 0 126 106

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/8000

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ptime:20

6e61a7b2-ce59-11e3-bc32-179817d66480 a=sendrecv

6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256
E6:78:EC:39:F5:3C:05:52:3A:9D:CF:39:C3:A1:48:AD:F4:5F:92:93:FC:80:B5:E0:04:0E:9F:59:DA:6D:23:17

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux

6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:18598 IN IP4 192.168.1.103

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797
cname:1sSsiCPODDHeGKGc

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797
msid:3W5SBZTwGbY3LccBFF9pBCOTX46igew9 a0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797
mslabel:3W5SBZTwGbY3LccBFF9pBCOTX46igew9

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797
label:3W5SBZTwGbY3LccBFF9pBCOTX46igew9a0

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:fFrH85qVZn46sq5j

6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:JOCRghvKL3PhDlkJ

6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:1860017488 1 udp 659136
192.168.1.103 18598 typ host generation 0

6e61a7b2-ce59-11e3-bc32-179817d66480

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[completed][200]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE]
mod_dptools.c:1232 Channel [sofia/internal/1001@vortex01.no-ip.info] has
been answered

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_channel.c:3680 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change EARLY -> ACTIVE

6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info conference(3000-192.168.1.103@default)

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9366 using channel sound
prefix: /usr/local/freeswitch_master/sounds/en/us/callie

2014-04-28 00:15:25.048231 [DEBUG] mod_conference.c:2526 Setup timer
success interval: 20  samples: 160

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_conference.c:8052 Raw Codec Activation Success L16@8000hz 1 channel 20ms

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_conference.c:8097 Raw Codec Activation Success L16@8000hz 1 channel 20ms

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_codec.c:221 sofia/internal/1001@vortex01.no-ip.info Push codec
L16:70

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '0' to 'mute'

2014-04-28 00:15:25.048231 [INFO] switch_ivr_async.c:212 Digit parser
mod_conference: Setting realm to 'conf'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 0/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5860

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '*' to 'deaf mute'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding */conf/0 callback: 0x7f898a246050 data:
0x7f89940b5890

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '9' to 'energy up'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 9/conf/0 callback: 0x7f898a246050 data:
0x7f89940b58c0

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '8' to 'energy equ'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 8/conf/0 callback: 0x7f898a246050 data:
0x7f89940b58f0

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '7' to 'energy dn'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 7/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5920

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '3' to 'vol talk up'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 3/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5950

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '2' to 'vol talk zero'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 2/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5980

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '1' to 'vol talk dn'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 1/conf/0 callback: 0x7f898a246050 data:
0x7f89940b59b0

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '6' to 'vol listen up'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 6/conf/0 callback: 0x7f898a246050 data:
0x7f89940b59e0

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '5' to 'vol listen zero'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 5/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5a10

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '4' to 'vol listen dn'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 4/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5a40

2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '#' to 'hangup'

2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding #/conf/0 callback: 0x7f898a246050 data:
0x7f89940b5a70

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG]
mod_conference.c:4146 Setup timer soft success interval: 20  samples: 160

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[ready][200]

2014-04-28 00:15:27.788226 [DEBUG] mod_local_stream.c:498 Opening Stream
[moh/8000] 8000hz

Here is the FS log for working case (setup B - SIPml5 client is registered
directly to FS ):

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_channel.c:1053 New Channel
sofia/internal/1001@vortex01.no-ip.info[5f9a0e46-ce58-11e3-bc23-179817d66480]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_NEW

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
sofia.c:8264 sofia/internal/1001@vortex01.no-ip.info receiving invite from
192.168.1.103:5060 version: 1.5.11b git 048ce8d 2014-03-15 03:13:34Z 64bit

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[received][100]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
sofia.c:6173 Remote SDP:

5f9a0e46-ce58-11e3-bc23-179817d66480 v=0

5f9a0e46-ce58-11e3-bc23-179817d66480 o=- 8534549096408458000 2 IN IP4
127.0.0.1

5f9a0e46-ce58-11e3-bc23-179817d66480 s=Doubango Telecom - chrome

5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=group:BUNDLE audio

5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS
cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz

5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 54503 RTP/SAVPF 111 103 104 0
8 106 105 13 126

5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 95.180.32.20

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:111 opus/48000/2

5f9a0e46-ce58-11e3-bc23-179817d66480 a=fmtp:111 minptime=10

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:103 ISAC/16000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:104 ISAC/32000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:8 PCMA/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/32000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:105 CN/16000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:13 CN/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:54503 IN IP4 95.180.32.20

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 1 udp
2113937151 192.168.0.2 54503 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 2 udp
2113937151 192.168.0.2 54503 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 1 udp
2113937151 95.180.32.20 41816 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 2 udp
2113937151 95.180.32.20 41816 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 1 udp
1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503
generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 2 udp
1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503
generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 1 tcp 1509957375
192.168.0.2 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 2 tcp 1509957375
192.168.0.2 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 1 tcp 1509957375
95.180.32.20 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 2 tcp 1509957375
95.180.32.20 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:B6WU5lEITKLQx94t

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:Z1noiuPsuF0U6XAwzDkTkneP

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-options:google-ice

5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256
9F:D6:88:31:27:C7:B1:DA:39:45:36:86:50:58:04:52:D9:8E:44:08:3C:D6:B7:53:09:1C:B3:67:A1:CD:CF:91

5f9a0e46-ce58-11e3-bc23-179817d66480 a=setup:actpass

5f9a0e46-ce58-11e3-bc23-179817d66480 a=mid:audio

5f9a0e46-ce58-11e3-bc23-179817d66480 a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux

5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32
inline:xXLHbIkgifIrRZSCcaC0QFG6jhY8YYs0Stga+rfD

5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:uYgcHb6YugFaNXBre8+TvpQGWy2fxj/jMtfYnPZN

5f9a0e46-ce58-11e3-bc23-179817d66480 a=maxptime:60

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 cname:ByRT8xjphQkudQai

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
msid:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz
cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
mslabel:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
label:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0

5f9a0e46-ce58-11e3-bc23-179817d66480

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
sofia.c:6428 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_NEW
-> CS_INIT

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:486 (sofia/internal/1001@vortex01.no-ip.info)
State NEW

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_INIT

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info)
State INIT

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_sofia.c:87 sofia/internal/1001@vortex01.no-ip.info SOFIA INIT

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:40
sofia/internal/1001@vortex01.no-ip.infoStandard INIT

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:48 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_INIT -> CS_ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info)
State INIT going to sleep

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_channel.c:2177 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change DOWN -> RINGING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:164
sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [INFO]
mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context public

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->unloop] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~
/^true$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~
/^true$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->outside_call] continue=true

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Absolute Condition [outside_call]

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action set(outside_call=true)

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y
%T %z)})

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->call_debug] continue=true

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~
/^true$/ break=never

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->public_extensions] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [public_extensions]
destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [public->from_kamailio] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [from_kamailio]
network_addr(192.168.1.103) =~ /^192\.168\.1\.103$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [from_kamailio]
destination_number(3000) =~ /^(.+)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action transfer(3000 XML default)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_ROUTING -> CS_EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING going to sleep

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:256
sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info set(outside_call=true)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET
[outside_call]=[true]

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:07:50
+0200)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014
00:07:50 +0200]

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info transfer(3000 XML default)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_ivr.c:1831 (sofia/internal/1001@vortex01.no-ip.info) State Change
CS_EXECUTE -> CS_ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_ivr.c:1838 Transfer sofia/internal/1001@vortex01.no-ip.info to
XML[3000@default]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE going to sleep

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:164
sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [INFO]
mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context default

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->unloop] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~
/^true$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~
/^true$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->tod_example] continue=true

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->holiday_example] continue=true

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example]
break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->global-intercept] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [global-intercept]
destination_number(3000) =~ /^886$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group-intercept] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group-intercept]
destination_number(3000) =~ /^\*8$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->intercept-ext] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [intercept-ext]
destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->redial] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~
/^(redial|870)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->global] continue=true

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~
/^true$/ break=never

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info 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

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [global]
${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/
break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [global] ${switch_r_sdp}(v=0

5f9a0e46-ce58-11e3-bc23-179817d66480 o=- 8534549096408458000 2 IN IP4
127.0.0.1

5f9a0e46-ce58-11e3-bc23-179817d66480 s=Doubango Telecom - chrome

5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=group:BUNDLE audio

5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS
cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz

5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 54503 RTP/SAVPF 111 103 104 0
8 106 105 13 126

5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 95.180.32.20

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:111 opus/48000/2

5f9a0e46-ce58-11e3-bc23-179817d66480 a=fmtp:111 minptime=10

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:103 ISAC/16000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:104 ISAC/32000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:8 PCMA/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/32000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:105 CN/16000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:13 CN/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:54503 IN IP4 95.180.32.20

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 1 udp
2113937151 192.168.0.2 54503 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 2 udp
2113937151 192.168.0.2 54503 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 1 udp
2113937151 95.180.32.20 41816 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 2 udp
2113937151 95.180.32.20 41816 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 1 udp
1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503
generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 2 udp
1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503
generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 1 tcp 1509957375
192.168.0.2 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 2 tcp 1509957375
192.168.0.2 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 1 tcp 1509957375
95.180.32.20 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 2 tcp 1509957375
95.180.32.20 0 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:B6WU5lEITKLQx94t

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:Z1noiuPsuF0U6XAwzDkTkneP

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-options:google-ice

5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256
9F:D6:88:31:27:C7:B1:DA:39:45:36:86:50:58:04:52:D9:8E:44:08:3C:D6:B7:53:09:1C:B3:67:A1:CD:CF:91

5f9a0e46-ce58-11e3-bc23-179817d66480 a=setup:actpass

5f9a0e46-ce58-11e3-bc23-179817d66480 a=mid:audio

5f9a0e46-ce58-11e3-bc23-179817d66480 a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux

5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32
inline:xXLHbIkgifIrRZSCcaC0QFG6jhY8YYs0Stga+rfD

5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80
inline:uYgcHb6YugFaNXBre8+TvpQGWy2fxj/jMtfYnPZN

5f9a0e46-ce58-11e3-bc23-179817d66480 a=maxptime:60

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 cname:ByRT8xjphQkudQai

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
msid:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz
cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
mslabel:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585
label:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0

5f9a0e46-ce58-11e3-bc23-179817d66480 ) =~
/(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action set(rtp_secure_media=true)

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Absolute Condition [global]

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action
hash(insert/${domain_name}-last_dial/global/${uuid})

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y
%T %z)})

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->snom-demo-2] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-2]
destination_number(3000) =~ /^9001$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->snom-demo-1] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-1]
destination_number(3000) =~ /^9000$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000)
=~ /^88(\d{4})$|^\*0(.*)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000)
=~ /^779$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call_return] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call_return]
destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->del-group] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [del-group] destination_number(3000)
=~ /^80(\d{2})$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->add-group] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [add-group] destination_number(3000)
=~ /^81(\d{2})$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call-group-simo] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call-group-simo]
destination_number(3000) =~ /^82(\d{2})$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->call-group-order] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [call-group-order]
destination_number(3000) =~ /^83(\d{2})$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->extension-intercom]
continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [extension-intercom]
destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->Local_Extension] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension]
destination_number(3000) =~ /^(10[01][0-9]|1500)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->Local_Extension_Skinny]
continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension_Skinny]
destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_sales] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_sales]
destination_number(3000) =~ /^2000$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_support]
continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_support]
destination_number(3000) =~ /^2001$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->group_dial_billing]
continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [group_dial_billing]
destination_number(3000) =~ /^2002$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->operator] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000)
=~ /^(operator|0)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->vmain] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [vmain] destination_number(3000) =~
/^vmain$|^4000$|^\*98$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->sip_uri] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~
/^sip:(.*)$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info parsing [default->nb_conferences] continue=false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Regex (PASS) [nb_conferences]
destination_number(3000) =~ /^(30\d{2})$/ break=on-false

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action answer()

5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/
1001@vortex01.no-ip.info Action conference(3000-${domain_name}@default)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info)
State Change CS_ROUTING -> CS_EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_session.c:1385 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info)
State ROUTING going to sleep

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info)
Running State Change CS_EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info)
State EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_state_machine.c:256
sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info set(rtp_secure_media=true)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET
[rtp_secure_media]=[true]

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.infohash(insert/192.168.1.103-spymap/1001/5f9a0e46-ce58-11e3-bc23-179817d66480)

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info hash(insert/192.168.1.103-last_dial/1001/3000)

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.infohash(insert/192.168.1.103-last_dial/global/5f9a0e46-ce58-11e3-bc23-179817d66480)

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:07:50
+0200)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014
00:07:50 +0200]

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info answer()

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[opus:111:48000:60:0]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:103:16000:30:32000]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[ISAC:104:32000:30:32000]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3439 Audio Codec Compare [PCMU:0:8000:20:64000] ++++ is
saved as a match

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMU:0:8000:60:64000]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3439 Audio Codec Compare [PCMA:8:8000:20:64000] ++++ is
saved as a match

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[PCMA:8:8000:60:64000]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:105:16000:60:0]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[G722:9:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[PCMU:0:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[PCMA:8:8000:20:64000]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3385 Audio Codec Compare
[CN:13:8000:60:0]/[GSM:3:8000:20:13200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3311 Set telephone-event payload to 126

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2343 Set Codec
sofia/internal/1001@vortex01.no-ip.infoPCMU/8000 20 ms 160 samples
64000 bits

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_codec.c:111 sofia/internal/1001@vortex01.no-ip.info Original
read codec set to PCMU:0

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [WARNING]
switch_core_media.c:2544 NO candidate ACL defined, Defaulting to wan.auto

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host
addr: 192.168.0.2:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: host
addr: 192.168.0.2:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host
addr: 192.168.0.2:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: host
addr: 192.168.0.2:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host
addr: 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2577 Choose audio Candidate cid: 1 proto: udp type:
host addr: 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host
addr: 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2577 Choose audio Candidate cid: 2 proto: udp type:
host addr: 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: srflx
addr: 95.180.32.20:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: srflx
addr: 95.180.32.20:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: srflx
addr: 95.180.32.20:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: srflx
addr: 95.180.32.20:54503

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2706 setting remote audio ice addr to
95.180.32.20:41816based on candidate

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE]
switch_core_media.c:2726 setting remote rtcp audio addr to
95.180.32.20:41816 based on candidate

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:3628 Set 2833 dtmf send/recv payload to 126

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_core_media.c:4843 AUDIO RTP [sofia/internal/1001@vortex01.no-ip.info]
192.168.1.103 port 27400 -> 95.180.32.20 port 41816 codec: 0 ms: 20

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG]
switch_rtp.c:3334 Starting timer [soft] 160 bytes per 20ms

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO]
switch_core_media.c:5012 Activating Audio ICE

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE]
switch_rtp.c:3775 Activating RTP audio ICE:
B6WU5lEITKLQx94t:Wbcukd9ZO2TXi3JA 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO]
switch_core_media.c:5055 Activating RTCP PORT 41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_rtp.c:3680 RTCP send rate is: 10000 and packet rate is: 20000 Remote
Port: 41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_rtp.c:2153 Setting RTCP remote addr to 95.180.32.20:41816

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO]
switch_core_media.c:5063 Skipping RTCP ICE (Same as RTP)

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO]
switch_rtp.c:2878 Activate RTP/RTCP audio DTLS client

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_media.c:5187 Set 2833 dtmf send payload to 126

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_media.c:5193 Set 2833 dtmf receive payload to 126

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_media.c:5221 Set comfort noise payload to 106

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE]
sofia_media.c:92 Pre-Answer sofia/internal/1001@vortex01.no-ip.info!

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_channel.c:3393 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change RINGING -> EARLY

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
mod_sofia.c:775 Local SDP sofia/internal/1001@vortex01.no-ip.info:

5f9a0e46-ce58-11e3-bc23-179817d66480 v=0

5f9a0e46-ce58-11e3-bc23-179817d66480 o=FreeSWITCH 1398609070 1398609071 IN
IP4 192.168.1.103

5f9a0e46-ce58-11e3-bc23-179817d66480 s=FreeSWITCH

5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 192.168.1.103

5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS
LblgNN4d9nhJwffp4iz7IiaOPK33jqkV

5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 27400 RTP/SAVPF 0 126 106

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/8000

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ptime:20

5f9a0e46-ce58-11e3-bc23-179817d66480 a=sendrecv

5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256
E6:78:EC:39:F5:3C:05:52:3A:9D:CF:39:C3:A1:48:AD:F4:5F:92:93:FC:80:B5:E0:04:0E:9F:59:DA:6D:23:17

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux

5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:27400 IN IP4 192.168.1.103

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342
cname:rw24aW899fIwkOLf

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342
msid:LblgNN4d9nhJwffp4iz7IiaOPK33jqkV a0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342
mslabel:LblgNN4d9nhJwffp4iz7IiaOPK33jqkV

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342
label:LblgNN4d9nhJwffp4iz7IiaOPK33jqkVa0

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:Wbcukd9ZO2TXi3JA

5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:qBuqnsqnyZKIDlNq

5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1687001334 1 udp 659136
192.168.1.103 27400 typ host generation 0

5f9a0e46-ce58-11e3-bc23-179817d66480

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[completed][200]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE]
mod_dptools.c:1232 Channel [sofia/internal/1001@vortex01.no-ip.info] has
been answered

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_channel.c:3680 (sofia/internal/1001@vortex01.no-ip.info) Callstate
Change EARLY -> ACTIVE

5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/
1001@vortex01.no-ip.info conference(3000-192.168.1.103@default)

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9366 using channel sound
prefix: /usr/local/freeswitch_master/sounds/en/us/callie

2014-04-28 00:07:50.768880 [DEBUG] mod_conference.c:2526 Setup timer
success interval: 20  samples: 160

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
mod_conference.c:8052 Raw Codec Activation Success L16@8000hz 1 channel 20ms

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
mod_conference.c:8097 Raw Codec Activation Success L16@8000hz 1 channel 20ms

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_codec.c:221 sofia/internal/1001@vortex01.no-ip.info Push codec
L16:70

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '0' to 'mute'

2014-04-28 00:07:50.768880 [INFO] switch_ivr_async.c:212 Digit parser
mod_conference: Setting realm to 'conf'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 0/conf/0 callback: 0x7f898a246050 data:
0x7f89940bca00

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '*' to 'deaf mute'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding */conf/0 callback: 0x7f898a246050 data:
0x7f89940bca30

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '9' to 'energy up'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 9/conf/0 callback: 0x7f898a246050 data:
0x7f89940bca60

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '8' to 'energy equ'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 8/conf/0 callback: 0x7f898a246050 data:
0x7f89940bca90

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '7' to 'energy dn'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 7/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcac0

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '3' to 'vol talk up'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 3/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcaf0

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '2' to 'vol talk zero'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 2/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcb20

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '1' to 'vol talk dn'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 1/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcb50

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '6' to 'vol listen up'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 6/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcb80

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '5' to 'vol listen zero'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 5/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcbb0

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '4' to 'vol listen dn'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding 4/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcbe0

2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/
1001@vortex01.no-ip.info binding '#' to 'hangup'

2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser
mod_conference: binding #/conf/0 callback: 0x7f898a246050 data:
0x7f89940bcc10

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
switch_core_session.c:905 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG]
mod_conference.c:4146 Setup timer soft success interval: 20  samples: 160

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG]
switch_core_session.c:1050 Send signal sofia/internal/
1001@vortex01.no-ip.info [BREAK]

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.908250 [DEBUG]
sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state
[ready][200]

2014-04-28 00:07:53.528265 [DEBUG] mod_local_stream.c:498 Opening Stream
[moh/8000] 8000hz

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO]
switch_rtp.c:2701 Changing audio DTLS state from HANDSHAKE to SETUP

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO]
switch_rtp.c:2609 audio Fingerprint Verified.

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO]
switch_rtp.c:3151 Activating Audio Secure RTP SEND

2014-04-28 00:07:53.788881 [DEBUG] switch_core_sqldb.c:2551 Secure Type:
srtp:dtls:AES_CM_128_HMAC_SHA1_80

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO]
switch_rtp.c:3129 Activating Audio Secure RTP RECV

2014-04-28 00:07:53.788881 [DEBUG] switch_core_sqldb.c:2551 Secure Type:
srtp:dtls:AES_CM_128_HMAC_SHA1_80

5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO]
switch_rtp.c:2649 Changing audio DTLS state from SETUP to READY

[Freeswitch-dev] SIPml5--Kamailio--FS 1.5 -- getting 'ice not ready' message and no audio when join to conf.

Sasa Ivancev