April 2014 - freeswitch-dev@lists.freeswitch.org

[Freeswitch-dev] 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

[Freeswitch-dev] FreeSWITCH now supports basic.
by Michael Jerris 30 Apr '14

30 Apr '14

We have some exciting news to announce, we have added a new language module to FreeSWITCH, mod_basic is now available in the master branch. Feel free to take it for a test drive today and let us know how it works for you. More information available at http://freeswitch.org/node/480. Mike

3 2

[Freeswitch-dev] Weekly News and Notes
by Ken Rice 29 Apr '14

29 Apr '14

Hey FreeSWITCHers Join us tomorrow April 30th, 1PM Eastern on ClueCon Weekly. This week¹s topic, Tracking Down troubled commits in the source tree with git bisect. Where sip:888@conference.freeswitch.org or +1 919-386-9900 Whats git bisect you ask? Ever had one of those issues where you know it worked in a previous version, but now its broken? Git bisect helps you quickly isolate which commit changed things on you. Join us tomorrow as Travis Cross breaks out a little tutorial on how to utilize bisect! Have you registered for ClueCon? If not, get those registrations in today and tomorrow, use the discount code CLUECON2013 and get last years price! This code is only valid through the end of April and time is rapidly running out! Do you have a presentation you would like to show off at ClueCon? Be sure to submit your proposal to marketing(a)cluecon.com before its too late! Speaking slots are still available! -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

2 1

[Freeswitch-dev] confused about the flag code
by 张顺通 28 Apr '14

28 Apr '14

I am reading freeswitch code,there are a lot of flags,like: sofia_test_flag(tech_pvt, TFLAG_SDP) my question is : how can I know the meaning of TFLAG_SDP, is there anything similar to man manual? In this manual I can search the meaning of flag TFLAG_SDP. thanks

2 1

[Freeswitch-dev] Source Description RTCP Packets (SDES)
by deepak sujir 23 Apr '14

23 Apr '14

Hi, I am interested in capturing the Source Description RTCP Packets for the calls. Is there a way we can do the same? If not in order to implement this feature where shall I start looking in the freeswitch code. Thank you Deepak

1 0

[Freeswitch-dev] Friday JIRA Rampage 2PM Central / April 25h
by Brian West 23 Apr '14

23 Apr '14

This Friday we’ll be continuing our weekly JIRA rampage, I would like anyone with outstanding issues in JIRA that they would like to work on to join in. If you file a JIRA please pay attention, respond to our questions and TEST GIT MASTER, ‘Yes’ or ‘No’ or some other random string in the git master hash field when filing a JIRA is not acceptable. If the hash in that field is over 24 hours old then you didn’t do your part to help us help you. Help us help you PRETTY PLEASE! I really don’t like asking for more details then no reposes for 3 weeks… thats really not fair. I have OCD and that twerks my OCD badly :) Want to give back but can’t code? We have ways to pitching and help out, The FS Docs project and the FS Bug marshal program, Contact me if you wish to help, this may even be a gateway task to learning how to code in C! Its what happened to me when I was doing the same bug marshal tasks while working on Asterisk. We already have a few members of the community leading up The FS Docs project, we meet on Tuesdays at 2PM Central in 888 we can touch on this Friday too. Thanks, -- Brian West brian(a)freeswitch.org FreeSWITCH Solutions, LLC PO BOX 2531 Brookfield, WI 53008-2531 Twitter: @FreeSWITCH , @briankwest http://www.freeswitchbook.com http://www.freeswitchcookbook.com T: +1.918.420.9001 | F: +1.918.420.9002 | M: +1.918.424.WEST iNUM: +883 5100 1420 9001 ISN: 410*543 Skype:briankwest PGP Key: http://www.bkw.org:81/key.txt (AB93356707C76CED)

1 0

[Freeswitch-dev] ClueCon Weekly News and Notes
by Ken Rice 22 Apr '14

22 Apr '14

Hey FreeSWITCHers, Its getting to be that time of the week again... When: Wed 1PM Eastern, 10AM Pacific, 1700 GMT Where: sip:888@conference.freeswitch.org +1 919 386 9900 http://wiki.freeswitch.org/wiki/Weekly_Conference_Call_Calling_Instructions for more info This week: Jeff AKA krzee on irc from Liberty Private Network will be talking about secure darknet voip service. Future Events: have any ideas on what you want to see? Email me! Do you have a contact with someone working on a cool project? Put them in touch with me! We would love to have them on! Also don¹t forget to get your ClueCon Registration in @ http://www.cluecon.com. Register by the end of the month with CLUECON2013 as the discount code and save $100! -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0

[Freeswitch-dev] ClueCon Weekly! Join us today at 1PM Eastern
by Ken Rice 16 Apr '14

16 Apr '14

Hey guys, don¹t forget to join us today at 1PM EST for ClueCon Weekly on the 888 Bridge. sip:888@conference.freeswitch.org 1PM Eastern 10AM Pacific. -- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch Twitter: @FreeSWITCH

1 0

Re: [Freeswitch-dev] [Freeswitch-users] How to check the "fax_result_code" in the script? such as php script?
by sparklezou 15 Apr '14

15 Apr '14

Hi All, I checked and find two possible solution. But still NOT clear. 1. Run "fs_cli" in PHP script. But NOT find to correct command to show the channel variables. Nor the data for Mod_spandsp. https://wiki.freeswitch.org/wiki/Fs_cli#Simple 2. get info from "Mod event socket". But still NOT very clear how to get the Mod_spandsp event. https://wiki.freeswitch.org/wiki/Mod_event_socket#api https://wiki.freeswitch.org/wiki/Event_Socket_Library#events https://wiki.freeswitch.org/wiki/Event_List#Event-Name https://wiki.freeswitch.org/wiki/Mod_spandsp Please share your idea, how to handle such issue? Hope there is direct way to get the result code, then it will be easy to handle fax step in the script. Thanks! 2014-04-15 sparklezou 发件人：Michael Jerris <mike(a)jerris.com> 发送时间：2014-04-14 20:08 主题：Re: [Freeswitch-users] How to check the "fax_result_code" in the script? such as php script? 收件人："FreeSWITCH Users Help"<freeswitch-users(a)lists.freeswitch.org> 抄送： you could use an api hangup hook, or possibly do it in cdr processing. On Apr 10, 2014, at 10:39 PM, sparklezou <sparklezou(a)163.com> wrote: Hi Buddies, Reviewed the wiki, https://wiki.freeswitch.org/wiki/Mod_spandsp How to check the "fax_result_code" in the php script? Also other result info, such as fax_result_text, then could provide more detail response info to the client. Thanks! 2014-03-03 sparklezou _________________________________________________________________________

1 0

Re: [Freeswitch-dev] How to check the channel variables "fax_result_code" in the script? such as php script?
by sparklezou 14 Apr '14

14 Apr '14

Hi All, I also checked the wiki https://wiki.freeswitch.org/wiki/Channel_Variables Is there any way to get the channel variables in PHP script? Then it will be very easy to handle the following steps according to the result code. Thanks in advance! 2014-04-14 sparklezou 发件人："sparklezou"<sparklezou(a)163.com> 发送时间：2014-04-11 10:39 主题：How to check the "fax_result_code" in the script? such as php script? 收件人："freeswitch-users"<freeswitch-users(a)lists.freeswitch.org>,"freeswitch-dev"<freeswitch-dev(a)lists.freeswitch.org> 抄送： Hi Buddies, Reviewed the wiki, https://wiki.freeswitch.org/wiki/Mod_spandsp How to check the "fax_result_code" in the php script? Also other result info, such as fax_result_text, then could provide more detail response info to the client. Thanks! 2014-03-03 sparklezou

1 0