- FreeSWITCH-dev - FreeSWITCH Mailing Lists

SIPml5--Kamailio--FS 1.5 -- getting 'ice not ready' message and no audio when join to conf.
by Sasa Ivancev 15 May '14

15 May '14

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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info parsing [public->unloop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->outside_call] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Absolute Condition [outside_call] 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action set(outside_call=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->call_debug] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->public_extensions] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [public->from_kamailio] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Regex (PASS) [from_kamailio] destination_number(3000) =~ /^(.+)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.infoStandard EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)vortex01.no-ip.info SET [outside_call]=[true] 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info parsing [default->unloop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->tod_example] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->holiday_example] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example] break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->global-intercept] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->group-intercept] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->intercept-ext] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->redial] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->global] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)vortex01.no-ip.info Action set(rtp_secure_media=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Absolute Condition [global] 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/global/${uuid}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->snom-demo-2] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->snom-demo-1] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->eavesdrop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->eavesdrop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->call_return] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->del-group] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->add-group] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->call-group-simo] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->call-group-order] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->extension-intercom] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->Local_Extension] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->Local_Extension_Skinny] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_sales] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_support] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_billing] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->operator] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->vmain] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->sip_uri] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->nb_conferences] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Action answer() 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.infoStandard EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info! 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:3393 (sofia/internal/1001(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info) Callstate Change EARLY -> ACTIVE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Push codec L16:70 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info parsing [public->unloop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->outside_call] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Absolute Condition [outside_call] 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action set(outside_call=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->call_debug] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [public->public_extensions] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [public->from_kamailio] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Regex (PASS) [from_kamailio] destination_number(3000) =~ /^(.+)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.infoStandard EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)vortex01.no-ip.info SET [outside_call]=[true] 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info parsing [default->unloop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->tod_example] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->holiday_example] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example] break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->global-intercept] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->group-intercept] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->intercept-ext] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->redial] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->global] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)vortex01.no-ip.info Action set(rtp_secure_media=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Absolute Condition [global] 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/global/${uuid}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->snom-demo-2] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->snom-demo-1] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->eavesdrop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->eavesdrop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->call_return] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->del-group] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->add-group] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->call-group-simo] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->call-group-order] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->extension-intercom] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->Local_Extension] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->Local_Extension_Skinny] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_sales] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_support] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->group_dial_billing] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->operator] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->vmain] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info parsing [default->sip_uri] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)vortex01.no-ip.info parsing [default->nb_conferences] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Action answer() 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.infoStandard EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info! 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_channel.c:3393 (sofia/internal/1001(a)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(a)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(a)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(a)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(a)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(a)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(a)vortex01.no-ip.info) Callstate Change EARLY -> ACTIVE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001(a)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(a)vortex01.no-ip.info Push codec L16:70 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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

2 8

ClueCon Weekly! May 14th Giovanni Maruzzelli, GSMOpen and FreeSWITCH
by Ken Rice 12 May '14

12 May '14

FreeSWITCHers, Join us Wed May 14th at 1P Easter (1700 GMT) when Giovanni Maruzzelli will join us for an update on GSMOpen! What is GSMOpen you ask? Just a nice little connector you allow you to use USB GSM Devices. Also, don¹t forget ClueCon 2014 is rapidly approaching! This will be marking 10 years of ClueCon! You don¹t want to miss this one! Visit Cluecon.com for more details! K -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0

multicast rtp stream end in 1:00:51:43
by Xijing Dai 08 May '14

08 May '14

hey all, I am using mod_esf to send multicast rtp stream, it works fine at beginning. However, after 1 day 51 min 43 s, the udp packet won't be sent any more, and I print switch_rtp_write_frame return value, it said function is completed without any error. Does anyone knows the reason for it? Tools: I used wireshark to capture the packets. Cheers

1 0

ClueCon Weekly Today 1PM EDT... Join Us! Today VoIP Fraud!
by Ken Rice 07 May '14

07 May '14

Greetings! Today we have a last minute line up change on ClueCon Weekly. We¹ll reschedule the tmux talk for a later date. Today our speaker will be Simon Woodhead from Simwood. Simwood will be presenting on VoIP Fraud - Results based on our Darknet research and specifically our SIP Honeypot research! Join us sip:888@conference.freeswitch.org @ 1PM EDT sharp! That¹s 10AM PDT, or 1700 GMT!. If you need PSTN or other access methods see http://wiki.freeswitch.org/wiki/Weekly_Conference_Call_Calling_Instructions Hear you there! K -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0

ClueCon Weekly - May 7 TJ Chats about TMUX
by Ken Rice 05 May '14

05 May '14

Greetings! This week on ClueCon Weekly, TJ aka Bougyman is going to join us for a tmux demo and show you another tool to help administer servers without multiple SSH sessions rolling along. Join us Wed May 7 at 1P Eastern (1700 GMT) on sip:888@conference.freeswitch.org ! Call starts promptly at the top of the hour, so get in a little early and have a chat with the crew before we get started. Don¹t have time before? Hang around for the after party and get your FS questions answered. Coming up May 14th Giovanni Maruzzelli will be joining us to talk about linking FreeSWITCH to GSM networks via GSMOpen And don¹t forget to increase your Chances of winning @ ClueCon https://www.cluecon.com/increases-your-chances-of-winning/ --K -- Ken https://www.cluecon.com/increases-your-chances-of-winning/ http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0

Out of the Office (was: FreeSWITCH-dev Digest, Vol 95, Issue 1)
by Craig N. Zimmerman 05 May '14

05 May '14

Sorry I'm out of the Office for next two Days. Please Call 717-355-0571 x1023 for assistance. -- Craig Zimmerman Lead Developer Good’s Technology Services 717-355-0571 x1024 czimm(a)goodsstores.com<mailto:czimm@goodsstores.com>

1 0

Out of the Office (was: FreeSWITCH-dev Digest, Vol 95, Issue 1)
by Craig N. Zimmerman 05 May '14

05 May '14

Sorry I'm out of the Office for next two Days. Please Call 717-355-0571 x1023 for assistance. -- Craig Zimmerman Lead Developer Good’s Technology Services 717-355-0571 x1024 czimm(a)goodsstores.com<mailto:czimm@goodsstores.com>

1 0

ClueCon 2014 - Register Now Increases Your Chances of Winning
by Ken Rice 05 May '14

05 May '14

<https://cluecon.com>[image: https://billing.freeswitch.org/cart.php?gid=1]<https://billing.freeswitch.org/cart.php?gid=1> *ClueCon 2013 <https://cluecon.com/> - Register Today! <https://billing.freeswitch.org/cart.php?gid=1> * At ClueCon we love giving stuff away. Our sponsors have given us many cool toys to give away this year. We’ll have things from ThinkGeek, Snom, Packt Publishing and others! You can increase your chances of winning by registering right away. Everyone who registers by Friday May 16th will get 16 extra drawing tickets! You can register online right now. Just visit the ClueCon.com<http://cluecon.com/> or click Register Now <https://billing.freeswitch.org/cart.php?gid=1>. Great Presentations: WebRTC, VoIP Security, and Much Much More! Need help registering for cluecon? Operators are standing by! 1 877-742-CLUE The Schedule for speakers is starting to fill out! If you want to present at ClueCon this year, email your speaking proposal to marketing(a)cluecon.com! Feel free to contact us if you have any questions about this year's event. We look forward to seeing everyone in Chicago this August!

1 0

Jira and the BugHunt!
by Ken Rice 02 May '14

02 May '14

Hey Guys, Jira is offline this morning for a bit so we can do a little maintenance on it. Also 2PM EST Join us on SIP:888@conference.freeswitch.org for the FridayBugHunt! Be there! -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 1

Friday Bughunt and ClueCon Weekly! Announcements
by Ken Rice 01 May '14

01 May '14

Greetings FreeSWITCHers! Yesterday on CC Weekly, we had Travis Cross demo a nice tool for using git bisect to help track down bugs in FreeSWITCH! If you missed it torrents of the call (and previous calls) are up at http://torrents.freeswitch.org/ Tomorrow at 2PM Easter Join us on 888 for The FreeSWITCH Friday Bug Hunt! Have an open Jira you want to discuss or get some help with? Just want to help us work through some issues? Maybe you have a problem and you arent sure its a bug or a config issue. Join us on the bridge tomorrow! Access to the bridge is sip:888@conference.freeswitch.org or visit http://wiki.freeswitch.org/wiki/Weekly_Conference_Call_Calling_Instructions for additional access methods. Next Week (May 7) on ClueCon Weekly TJ Vanderpoel from Geoforce, Inc will be joining us with a tmux demo and how to. Tmux is one of those sysadmin tools that can help make your life easier if you live in terminal sessions all day! Keep in mind the calls start at 1PM Eastern, but the bridge is open early for meet and greet before and after the call! Join us early so you don¹t miss anything! Coming up on ClueCon Weekly May 14th Giovanni Maruzzelli aka gmaruzz on IRC will be updating us on GSMOpen. How to use GSM Mobile devices with FreeSWITCH. May 21st Lee Howard will be stopping by to update us on what¹s going on in the Hylafax+ world. May 28th Eric Crist, Director of IT with ClaimLynx, Inc and regular FreeSWITCH user and contributor will stop by to talk about Faxing with FreeSWITCH. If you have a topic you would like to have presented on ClueCon Weekly, Drop me an email (here or offlist) and lets get it on the schedule! K -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0