Hi all, I am using SIPml5 client and Kamailio server integrated with FS 1.5 ( behind NAT box ), according to this tutorial: http://kb.asipto.com/freeswitch:kamailio-3.3.x-freeswitch-1.2.x-sbc ( let's say setup A ) Also, I have another setup at the same location, FS 1.5 only (setup B) Joining to the conference from some remote location to setup B is always OK - I am getting two-way-audio. The issue is: when I try to join to the conference from the same remote location to setup A, I have no audio. From some other location, there is no issue even with setup A, I can get two-way-audio. In the non-working case, I see 'ice not ready' alert message in FS logs. I compared both working and non-working FS traces and couldn't figure out what is wrong. Can someone take a look and give some guidance what I am missing here ? Here is the FS log for non- working case (setup A - SIPml5 client is registered to Kamailio server which is integrated with FS as media bridge ): 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_channel.c:1053 New Channel sofia/internal/1001@vortex01.no-ip.info[6e61a7b2-ce59-11e3-bc32-179817d66480] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_NEW 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] sofia.c:8264 sofia/internal/1001@vortex01.no-ip.info receiving invite from 192.168.1.103:5060 version: 1.5.11b git 048ce8d 2014-03-15 03:13:34Z 64bit 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [received][100] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] sofia.c:6173 Remote SDP: 6e61a7b2-ce59-11e3-bc32-179817d66480 v=0 6e61a7b2-ce59-11e3-bc32-179817d66480 o=- 6933551414148352000 2 IN IP4 127.0.0.1 6e61a7b2-ce59-11e3-bc32-179817d66480 s=Doubango Telecom - chrome 6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=group:BUNDLE audio 6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo 6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 57265 RTP/SAVPF 111 103 104 0 8 106 105 13 126 6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 95.180.32.20 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:111 opus/48000/2 6e61a7b2-ce59-11e3-bc32-179817d66480 a=fmtp:111 minptime=10 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:103 ISAC/16000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:104 ISAC/32000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:8 PCMA/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/32000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:105 CN/16000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:13 CN/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:57265 IN IP4 95.180.32.20 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 1 udp 2113937151 192.168.0.100 57265 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 2 udp 2113937151 192.168.0.100 57265 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 1 udp 1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265 generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 2 udp 1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265 generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 1 tcp 1509957375 192.168.0.100 0 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 2 tcp 1509957375 192.168.0.100 0 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:gHnLryRtXCOYse+I 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:gD1S2kW13thhaaqe30SyLfi3 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-options:google-ice 6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256 39:84:0F:18:1B:DA:DB:39:D4:EB:80:4D:4C:4B:3A:DB:0B:A1:B9:7C:4B:2C:C9:00:4F:A3:9A:F9:66:C3:70:F4 6e61a7b2-ce59-11e3-bc32-179817d66480 a=setup:actpass 6e61a7b2-ce59-11e3-bc32-179817d66480 a=mid:audio 6e61a7b2-ce59-11e3-bc32-179817d66480 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux 6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:Cz/8x3EVJwmDLmLlp5EbgpSVH4sK52PZjT9uA9E5 6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:GqldZ39QV90ToD8lVZVcimBM+Qf422rD4FlZK0nV 6e61a7b2-ce59-11e3-bc32-179817d66480 a=maxptime:60 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 cname:S5DliNJMaHoIOHFf 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 msid:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo bceee12b-94e6-49e5-acab-bb334f96e7c6 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 mslabel:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 label:bceee12b-94e6-49e5-acab-bb334f96e7c6 6e61a7b2-ce59-11e3-bc32-179817d66480 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] sofia.c:6428 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_NEW -> CS_INIT 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:486 (sofia/internal/1001@vortex01.no-ip.info) State NEW 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_INIT 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info) State INIT 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:87 sofia/internal/1001@vortex01.no-ip.info SOFIA INIT 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@vortex01.no-ip.infoStandard INIT 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_INIT -> CS_ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info) State INIT going to sleep 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:2177 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change DOWN -> RINGING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:164 sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context public 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->unloop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->outside_call] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Absolute Condition [outside_call] 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action set(outside_call=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->call_debug] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->public_extensions] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [public_extensions] destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->from_kamailio] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [from_kamailio] network_addr(192.168.1.103) =~ /^192\.168\.1\.103$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [from_kamailio] destination_number(3000) =~ /^(.+)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action transfer(3000 XML default) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_ROUTING -> CS_EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING going to sleep 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:256 sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info set(outside_call=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET [outside_call]=[true] 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:15:25 +0200) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014 00:15:25 +0200] 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info transfer(3000 XML default) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr.c:1831 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_EXECUTE -> CS_ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_ivr.c:1838 Transfer sofia/internal/1001@vortex01.no-ip.info to XML[3000@default] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE going to sleep 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:164 sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context default 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->unloop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->tod_example] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->holiday_example] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example] break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->global-intercept] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group-intercept] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->intercept-ext] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->redial] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->global] continue=true 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [global] ${switch_r_sdp}(v=0 6e61a7b2-ce59-11e3-bc32-179817d66480 o=- 6933551414148352000 2 IN IP4 127.0.0.1 6e61a7b2-ce59-11e3-bc32-179817d66480 s=Doubango Telecom - chrome 6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=group:BUNDLE audio 6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo 6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 57265 RTP/SAVPF 111 103 104 0 8 106 105 13 126 6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 95.180.32.20 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:111 opus/48000/2 6e61a7b2-ce59-11e3-bc32-179817d66480 a=fmtp:111 minptime=10 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:103 ISAC/16000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:104 ISAC/32000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:8 PCMA/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/32000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:105 CN/16000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:13 CN/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:57265 IN IP4 95.180.32.20 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 1 udp 2113937151 192.168.0.100 57265 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:2131708102 2 udp 2113937151 192.168.0.100 57265 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 1 udp 1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265 generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:4266086002 2 udp 1845501695 95.180.32.20 57265 typ srflx raddr 192.168.0.100 rport 57265 generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 1 tcp 1509957375 192.168.0.100 0 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:831304758 2 tcp 1509957375 192.168.0.100 0 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:gHnLryRtXCOYse+I 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:gD1S2kW13thhaaqe30SyLfi3 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-options:google-ice 6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256 39:84:0F:18:1B:DA:DB:39:D4:EB:80:4D:4C:4B:3A:DB:0B:A1:B9:7C:4B:2C:C9:00:4F:A3:9A:F9:66:C3:70:F4 6e61a7b2-ce59-11e3-bc32-179817d66480 a=setup:actpass 6e61a7b2-ce59-11e3-bc32-179817d66480 a=mid:audio 6e61a7b2-ce59-11e3-bc32-179817d66480 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux 6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:Cz/8x3EVJwmDLmLlp5EbgpSVH4sK52PZjT9uA9E5 6e61a7b2-ce59-11e3-bc32-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:GqldZ39QV90ToD8lVZVcimBM+Qf422rD4FlZK0nV 6e61a7b2-ce59-11e3-bc32-179817d66480 a=maxptime:60 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 cname:S5DliNJMaHoIOHFf 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 msid:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo bceee12b-94e6-49e5-acab-bb334f96e7c6 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 mslabel:NmmoDAX4BXNkxNGYFECZkD9Ka6ejHZyPMYUo 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:1999392369 label:bceee12b-94e6-49e5-acab-bb334f96e7c6 6e61a7b2-ce59-11e3-bc32-179817d66480 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action set(rtp_secure_media=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Absolute Condition [global] 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/global/${uuid}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->snom-demo-2] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-2] destination_number(3000) =~ /^9001$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->snom-demo-1] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-1] destination_number(3000) =~ /^9000$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call_return] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call_return] destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->del-group] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [del-group] destination_number(3000) =~ /^80(\d{2})$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->add-group] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [add-group] destination_number(3000) =~ /^81(\d{2})$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call-group-simo] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call-group-simo] destination_number(3000) =~ /^82(\d{2})$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call-group-order] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call-group-order] destination_number(3000) =~ /^83(\d{2})$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->extension-intercom] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [extension-intercom] destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->Local_Extension] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension] destination_number(3000) =~ /^(10[01][0-9]|1500)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->Local_Extension_Skinny] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension_Skinny] destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_sales] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_sales] destination_number(3000) =~ /^2000$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_support] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_support] destination_number(3000) =~ /^2001$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_billing] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_billing] destination_number(3000) =~ /^2002$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->operator] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->vmain] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [vmain] destination_number(3000) =~ /^vmain$|^4000$|^\*98$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->sip_uri] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->nb_conferences] continue=false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [nb_conferences] destination_number(3000) =~ /^(30\d{2})$/ break=on-false 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action answer() 6e61a7b2-ce59-11e3-bc32-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action conference(3000-${domain_name}@default) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_ROUTING -> CS_EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING going to sleep 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_state_machine.c:256 sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info set(rtp_secure_media=true) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET [rtp_secure_media]=[true] 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.infohash(insert/192.168.1.103-spymap/1001/6e61a7b2-ce59-11e3-bc32-179817d66480) 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info hash(insert/192.168.1.103-last_dial/1001/3000) 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.infohash(insert/192.168.1.103-last_dial/global/6e61a7b2-ce59-11e3-bc32-179817d66480) 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:15:25 +0200) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014 00:15:25 +0200] 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info answer() 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3439 Audio Codec Compare [PCMU:0:8000:20:64000] ++++ is saved as a match 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3439 Audio Codec Compare [PCMA:8:8000:20:64000] ++++ is saved as a match 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[G722:9:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[PCMU:0:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[PCMA:8:8000:20:64000] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[GSM:3:8000:20:13200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3311 Set telephone-event payload to 126 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:2343 Set Codec sofia/internal/1001@vortex01.no-ip.infoPCMU/8000 20 ms 160 samples 64000 bits 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@vortex01.no-ip.info Original read codec set to PCMU:0 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [WARNING] switch_core_media.c:2544 NO candidate ACL defined, Defaulting to wan.auto 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.0.100:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.100:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.0.100:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.100:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: srflx addr: 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2577 Choose audio Candidate cid: 1 proto: udp type: srflx addr: 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: srflx addr: 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2577 Choose audio Candidate cid: 2 proto: udp type: srflx addr: 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2706 setting remote audio ice addr to 95.180.32.20:57265based on candidate 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_core_media.c:2726 setting remote rtcp audio addr to 95.180.32.20:57265 based on candidate 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:3628 Set 2833 dtmf send/recv payload to 126 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:4843 AUDIO RTP [sofia/internal/1001@vortex01.no-ip.info] 192.168.1.103 port 18598 -> 95.180.32.20 port 57265 codec: 0 ms: 20 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_rtp.c:3334 Starting timer [soft] 160 bytes per 20ms 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] switch_core_media.c:5012 Activating Audio ICE 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] switch_rtp.c:3775 Activating RTP audio ICE: gHnLryRtXCOYse+I:fFrH85qVZn46sq5j 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] switch_core_media.c:5055 Activating RTCP PORT 57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_rtp.c:3680 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_rtp.c:2153 Setting RTCP remote addr to 95.180.32.20:57265 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] switch_core_media.c:5063 Skipping RTCP ICE (Same as RTP) 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [INFO] switch_rtp.c:2878 Activate RTP/RTCP audio DTLS client 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:5187 Set 2833 dtmf send payload to 126 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:5193 Set 2833 dtmf receive payload to 126 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_media.c:5221 Set comfort noise payload to 106 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@vortex01.no-ip.info! 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:3393 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change RINGING -> EARLY 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_sofia.c:775 Local SDP sofia/internal/1001@vortex01.no-ip.info: 6e61a7b2-ce59-11e3-bc32-179817d66480 v=0 6e61a7b2-ce59-11e3-bc32-179817d66480 o=FreeSWITCH 1398618327 1398618328 IN IP4 192.168.1.103 6e61a7b2-ce59-11e3-bc32-179817d66480 s=FreeSWITCH 6e61a7b2-ce59-11e3-bc32-179817d66480 c=IN IP4 192.168.1.103 6e61a7b2-ce59-11e3-bc32-179817d66480 t=0 0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=msid-semantic: WMS 3W5SBZTwGbY3LccBFF9pBCOTX46igew9 6e61a7b2-ce59-11e3-bc32-179817d66480 m=audio 18598 RTP/SAVPF 0 126 106 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:0 PCMU/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:126 telephone-event/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtpmap:106 CN/8000 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ptime:20 6e61a7b2-ce59-11e3-bc32-179817d66480 a=sendrecv 6e61a7b2-ce59-11e3-bc32-179817d66480 a=fingerprint:sha-256 E6:78:EC:39:F5:3C:05:52:3A:9D:CF:39:C3:A1:48:AD:F4:5F:92:93:FC:80:B5:E0:04:0E:9F:59:DA:6D:23:17 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp-mux 6e61a7b2-ce59-11e3-bc32-179817d66480 a=rtcp:18598 IN IP4 192.168.1.103 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797 cname:1sSsiCPODDHeGKGc 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797 msid:3W5SBZTwGbY3LccBFF9pBCOTX46igew9 a0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797 mslabel:3W5SBZTwGbY3LccBFF9pBCOTX46igew9 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ssrc:3613542797 label:3W5SBZTwGbY3LccBFF9pBCOTX46igew9a0 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-ufrag:fFrH85qVZn46sq5j 6e61a7b2-ce59-11e3-bc32-179817d66480 a=ice-pwd:JOCRghvKL3PhDlkJ 6e61a7b2-ce59-11e3-bc32-179817d66480 a=candidate:1860017488 1 udp 659136 192.168.1.103 18598 typ host generation 0 6e61a7b2-ce59-11e3-bc32-179817d66480 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [completed][200] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [NOTICE] mod_dptools.c:1232 Channel [sofia/internal/1001@vortex01.no-ip.info] has been answered 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_channel.c:3680 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change EARLY -> ACTIVE 6e61a7b2-ce59-11e3-bc32-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info conference(3000-192.168.1.103@default) 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9366 using channel sound prefix: /usr/local/freeswitch_master/sounds/en/us/callie 2014-04-28 00:15:25.048231 [DEBUG] mod_conference.c:2526 Setup timer success interval: 20 samples: 160 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_conference.c:8052 Raw Codec Activation Success L16@8000hz 1 channel 20ms 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_conference.c:8097 Raw Codec Activation Success L16@8000hz 1 channel 20ms 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_codec.c:221 sofia/internal/1001@vortex01.no-ip.info Push codec L16:70 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '0' to 'mute' 2014-04-28 00:15:25.048231 [INFO] switch_ivr_async.c:212 Digit parser mod_conference: Setting realm to 'conf' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5860 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '*' to 'deaf mute' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding */conf/0 callback: 0x7f898a246050 data: 0x7f89940b5890 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '9' to 'energy up' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f898a246050 data: 0x7f89940b58c0 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '8' to 'energy equ' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f898a246050 data: 0x7f89940b58f0 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '7' to 'energy dn' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5920 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '3' to 'vol talk up' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5950 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '2' to 'vol talk zero' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5980 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '1' to 'vol talk dn' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f898a246050 data: 0x7f89940b59b0 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '6' to 'vol listen up' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f898a246050 data: 0x7f89940b59e0 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '5' to 'vol listen zero' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5a10 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '4' to 'vol listen dn' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5a40 2014-04-28 00:15:25.048231 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '#' to 'hangup' 2014-04-28 00:15:25.048231 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding #/conf/0 callback: 0x7f898a246050 data: 0x7f89940b5a70 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.048231 [DEBUG] mod_conference.c:4146 Setup timer soft success interval: 20 samples: 160 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 6e61a7b2-ce59-11e3-bc32-179817d66480 2014-04-28 00:15:25.308229 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [ready][200] 2014-04-28 00:15:27.788226 [DEBUG] mod_local_stream.c:498 Opening Stream [moh/8000] 8000hz Here is the FS log for working case (setup B - SIPml5 client is registered directly to FS ): 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_channel.c:1053 New Channel sofia/internal/1001@vortex01.no-ip.info[5f9a0e46-ce58-11e3-bc23-179817d66480] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_NEW 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] sofia.c:8264 sofia/internal/1001@vortex01.no-ip.info receiving invite from 192.168.1.103:5060 version: 1.5.11b git 048ce8d 2014-03-15 03:13:34Z 64bit 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [received][100] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] sofia.c:6173 Remote SDP: 5f9a0e46-ce58-11e3-bc23-179817d66480 v=0 5f9a0e46-ce58-11e3-bc23-179817d66480 o=- 8534549096408458000 2 IN IP4 127.0.0.1 5f9a0e46-ce58-11e3-bc23-179817d66480 s=Doubango Telecom - chrome 5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=group:BUNDLE audio 5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz 5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 54503 RTP/SAVPF 111 103 104 0 8 106 105 13 126 5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 95.180.32.20 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:111 opus/48000/2 5f9a0e46-ce58-11e3-bc23-179817d66480 a=fmtp:111 minptime=10 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:103 ISAC/16000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:104 ISAC/32000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:8 PCMA/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/32000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:105 CN/16000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:13 CN/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:54503 IN IP4 95.180.32.20 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 1 udp 2113937151 192.168.0.2 54503 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 2 udp 2113937151 192.168.0.2 54503 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 1 udp 2113937151 95.180.32.20 41816 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 2 udp 2113937151 95.180.32.20 41816 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 1 udp 1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503 generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 2 udp 1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503 generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 1 tcp 1509957375 192.168.0.2 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 2 tcp 1509957375 192.168.0.2 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 1 tcp 1509957375 95.180.32.20 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 2 tcp 1509957375 95.180.32.20 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:B6WU5lEITKLQx94t 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:Z1noiuPsuF0U6XAwzDkTkneP 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-options:google-ice 5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256 9F:D6:88:31:27:C7:B1:DA:39:45:36:86:50:58:04:52:D9:8E:44:08:3C:D6:B7:53:09:1C:B3:67:A1:CD:CF:91 5f9a0e46-ce58-11e3-bc23-179817d66480 a=setup:actpass 5f9a0e46-ce58-11e3-bc23-179817d66480 a=mid:audio 5f9a0e46-ce58-11e3-bc23-179817d66480 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux 5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:xXLHbIkgifIrRZSCcaC0QFG6jhY8YYs0Stga+rfD 5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:uYgcHb6YugFaNXBre8+TvpQGWy2fxj/jMtfYnPZN 5f9a0e46-ce58-11e3-bc23-179817d66480 a=maxptime:60 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 cname:ByRT8xjphQkudQai 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 msid:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 mslabel:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 label:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0 5f9a0e46-ce58-11e3-bc23-179817d66480 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] sofia.c:6428 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_NEW -> CS_INIT 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:486 (sofia/internal/1001@vortex01.no-ip.info) State NEW 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_INIT 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info) State INIT 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_sofia.c:87 sofia/internal/1001@vortex01.no-ip.info SOFIA INIT 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@vortex01.no-ip.infoStandard INIT 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_INIT -> CS_ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/1001@vortex01.no-ip.info) State INIT going to sleep 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_channel.c:2177 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change DOWN -> RINGING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:164 sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [INFO] mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context public 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->unloop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->outside_call] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Absolute Condition [outside_call] 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action set(outside_call=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->call_debug] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->public_extensions] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [public_extensions] destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [public->from_kamailio] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [from_kamailio] network_addr(192.168.1.103) =~ /^192\.168\.1\.103$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [from_kamailio] destination_number(3000) =~ /^(.+)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action transfer(3000 XML default) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_ROUTING -> CS_EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING going to sleep 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:256 sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info set(outside_call=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET [outside_call]=[true] 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:07:50 +0200) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014 00:07:50 +0200] 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info transfer(3000 XML default) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_ivr.c:1831 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_EXECUTE -> CS_ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_ivr.c:1838 Transfer sofia/internal/1001@vortex01.no-ip.info to XML[3000@default] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE going to sleep 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_sofia.c:123 sofia/internal/1001@vortex01.no-ip.info SOFIA ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:164 sofia/internal/1001@vortex01.no-ip.infoStandard ROUTING 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [INFO] mod_dialplan_xml.c:558 Processing 1001 <1001>->3000 in context default 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->unloop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->tod_example] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [tod_example] break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->holiday_example] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Date/TimeMatch (FAIL) [holiday_example] break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->global-intercept] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group-intercept] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->intercept-ext] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->redial] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->global] continue=true 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [global] ${switch_r_sdp}(v=0 5f9a0e46-ce58-11e3-bc23-179817d66480 o=- 8534549096408458000 2 IN IP4 127.0.0.1 5f9a0e46-ce58-11e3-bc23-179817d66480 s=Doubango Telecom - chrome 5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=group:BUNDLE audio 5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz 5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 54503 RTP/SAVPF 111 103 104 0 8 106 105 13 126 5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 95.180.32.20 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:111 opus/48000/2 5f9a0e46-ce58-11e3-bc23-179817d66480 a=fmtp:111 minptime=10 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:103 ISAC/16000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:104 ISAC/32000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:8 PCMA/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/32000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:105 CN/16000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:13 CN/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:54503 IN IP4 95.180.32.20 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 1 udp 2113937151 192.168.0.2 54503 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1511920713 2 udp 2113937151 192.168.0.2 54503 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 1 udp 2113937151 95.180.32.20 41816 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:2117648224 2 udp 2113937151 95.180.32.20 41816 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 1 udp 1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503 generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:4081163164 2 udp 1845501695 95.180.32.20 54503 typ srflx raddr 192.168.0.2 rport 54503 generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 1 tcp 1509957375 192.168.0.2 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:345893049 2 tcp 1509957375 192.168.0.2 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 1 tcp 1509957375 95.180.32.20 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:817578896 2 tcp 1509957375 95.180.32.20 0 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:B6WU5lEITKLQx94t 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:Z1noiuPsuF0U6XAwzDkTkneP 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-options:google-ice 5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256 9F:D6:88:31:27:C7:B1:DA:39:45:36:86:50:58:04:52:D9:8E:44:08:3C:D6:B7:53:09:1C:B3:67:A1:CD:CF:91 5f9a0e46-ce58-11e3-bc23-179817d66480 a=setup:actpass 5f9a0e46-ce58-11e3-bc23-179817d66480 a=mid:audio 5f9a0e46-ce58-11e3-bc23-179817d66480 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux 5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:xXLHbIkgifIrRZSCcaC0QFG6jhY8YYs0Stga+rfD 5f9a0e46-ce58-11e3-bc23-179817d66480 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:uYgcHb6YugFaNXBre8+TvpQGWy2fxj/jMtfYnPZN 5f9a0e46-ce58-11e3-bc23-179817d66480 a=maxptime:60 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 cname:ByRT8xjphQkudQai 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 msid:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 mslabel:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQz 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:265872585 label:cd4HvOjDZ0ZS9Gq0k0AzFPnmCi6um1xcGaQza0 5f9a0e46-ce58-11e3-bc23-179817d66480 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action set(rtp_secure_media=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Absolute Condition [global] 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action hash(insert/${domain_name}-last_dial/global/${uuid}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->snom-demo-2] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-2] destination_number(3000) =~ /^9001$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->snom-demo-1] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [snom-demo-1] destination_number(3000) =~ /^9000$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->eavesdrop] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call_return] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call_return] destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->del-group] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [del-group] destination_number(3000) =~ /^80(\d{2})$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->add-group] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [add-group] destination_number(3000) =~ /^81(\d{2})$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call-group-simo] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call-group-simo] destination_number(3000) =~ /^82(\d{2})$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->call-group-order] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [call-group-order] destination_number(3000) =~ /^83(\d{2})$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->extension-intercom] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [extension-intercom] destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->Local_Extension] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension] destination_number(3000) =~ /^(10[01][0-9]|1500)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->Local_Extension_Skinny] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [Local_Extension_Skinny] destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_sales] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_sales] destination_number(3000) =~ /^2000$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_support] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_support] destination_number(3000) =~ /^2001$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->group_dial_billing] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [group_dial_billing] destination_number(3000) =~ /^2002$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->operator] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->vmain] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [vmain] destination_number(3000) =~ /^vmain$|^4000$|^\*98$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->sip_uri] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info parsing [default->nb_conferences] continue=false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Regex (PASS) [nb_conferences] destination_number(3000) =~ /^(30\d{2})$/ break=on-false 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action answer() 5f9a0e46-ce58-11e3-bc23-179817d66480 Dialplan: sofia/internal/ 1001@vortex01.no-ip.info Action conference(3000-${domain_name}@default) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/1001@vortex01.no-ip.info) State Change CS_ROUTING -> CS_EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_session.c:1385 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/1001@vortex01.no-ip.info) State ROUTING going to sleep 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1001@vortex01.no-ip.info) Running State Change CS_EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/1001@vortex01.no-ip.info) State EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_sofia.c:178 sofia/internal/1001@vortex01.no-ip.info SOFIA EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_state_machine.c:256 sofia/internal/1001@vortex01.no-ip.infoStandard EXECUTE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info set(rtp_secure_media=true) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] mod_dptools.c:1409 sofia/internal/1001@vortex01.no-ip.info SET [rtp_secure_media]=[true] 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.infohash(insert/192.168.1.103-spymap/1001/5f9a0e46-ce58-11e3-bc23-179817d66480) 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info hash(insert/192.168.1.103-last_dial/1001/3000) 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.infohash(insert/192.168.1.103-last_dial/global/5f9a0e46-ce58-11e3-bc23-179817d66480) 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info export(RFC2822_DATE=Mon, 28 Apr 2014 00:07:50 +0200) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 28 Apr 2014 00:07:50 +0200] 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info answer() 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [opus:111:48000:60:0]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:103:16000:30:32000]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [ISAC:104:32000:30:32000]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3439 Audio Codec Compare [PCMU:0:8000:20:64000] ++++ is saved as a match 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMU:0:8000:60:64000]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3439 Audio Codec Compare [PCMA:8:8000:20:64000] ++++ is saved as a match 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [PCMA:8:8000:60:64000]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:105:16000:60:0]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[G722:9:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[PCMU:0:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[PCMA:8:8000:20:64000] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3385 Audio Codec Compare [CN:13:8000:60:0]/[GSM:3:8000:20:13200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3311 Set telephone-event payload to 126 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2343 Set Codec sofia/internal/1001@vortex01.no-ip.infoPCMU/8000 20 ms 160 samples 64000 bits 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@vortex01.no-ip.info Original read codec set to PCMU:0 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [WARNING] switch_core_media.c:2544 NO candidate ACL defined, Defaulting to wan.auto 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host addr: 192.168.0.2:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.0.2:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host addr: 192.168.0.2:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.0.2:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: host addr: 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2577 Choose audio Candidate cid: 1 proto: udp type: host addr: 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: host addr: 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2577 Choose audio Candidate cid: 2 proto: udp type: host addr: 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 1 proto: udp type: srflx addr: 95.180.32.20:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 1 proto: udp type: srflx addr: 95.180.32.20:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:2568 Checking Candidate cid: 2 proto: udp type: srflx addr: 95.180.32.20:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2582 Save audio Candidate cid: 2 proto: udp type: srflx addr: 95.180.32.20:54503 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2706 setting remote audio ice addr to 95.180.32.20:41816based on candidate 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [NOTICE] switch_core_media.c:2726 setting remote rtcp audio addr to 95.180.32.20:41816 based on candidate 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:3628 Set 2833 dtmf send/recv payload to 126 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_core_media.c:4843 AUDIO RTP [sofia/internal/1001@vortex01.no-ip.info] 192.168.1.103 port 27400 -> 95.180.32.20 port 41816 codec: 0 ms: 20 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.748212 [DEBUG] switch_rtp.c:3334 Starting timer [soft] 160 bytes per 20ms 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO] switch_core_media.c:5012 Activating Audio ICE 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE] switch_rtp.c:3775 Activating RTP audio ICE: B6WU5lEITKLQx94t:Wbcukd9ZO2TXi3JA 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO] switch_core_media.c:5055 Activating RTCP PORT 41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_rtp.c:3680 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_rtp.c:2153 Setting RTCP remote addr to 95.180.32.20:41816 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO] switch_core_media.c:5063 Skipping RTCP ICE (Same as RTP) 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [INFO] switch_rtp.c:2878 Activate RTP/RTCP audio DTLS client 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_media.c:5187 Set 2833 dtmf send payload to 126 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_media.c:5193 Set 2833 dtmf receive payload to 126 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_media.c:5221 Set comfort noise payload to 106 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@vortex01.no-ip.info! 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_channel.c:3393 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change RINGING -> EARLY 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] mod_sofia.c:775 Local SDP sofia/internal/1001@vortex01.no-ip.info: 5f9a0e46-ce58-11e3-bc23-179817d66480 v=0 5f9a0e46-ce58-11e3-bc23-179817d66480 o=FreeSWITCH 1398609070 1398609071 IN IP4 192.168.1.103 5f9a0e46-ce58-11e3-bc23-179817d66480 s=FreeSWITCH 5f9a0e46-ce58-11e3-bc23-179817d66480 c=IN IP4 192.168.1.103 5f9a0e46-ce58-11e3-bc23-179817d66480 t=0 0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=msid-semantic: WMS LblgNN4d9nhJwffp4iz7IiaOPK33jqkV 5f9a0e46-ce58-11e3-bc23-179817d66480 m=audio 27400 RTP/SAVPF 0 126 106 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:0 PCMU/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:126 telephone-event/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtpmap:106 CN/8000 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ptime:20 5f9a0e46-ce58-11e3-bc23-179817d66480 a=sendrecv 5f9a0e46-ce58-11e3-bc23-179817d66480 a=fingerprint:sha-256 E6:78:EC:39:F5:3C:05:52:3A:9D:CF:39:C3:A1:48:AD:F4:5F:92:93:FC:80:B5:E0:04:0E:9F:59:DA:6D:23:17 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp-mux 5f9a0e46-ce58-11e3-bc23-179817d66480 a=rtcp:27400 IN IP4 192.168.1.103 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342 cname:rw24aW899fIwkOLf 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342 msid:LblgNN4d9nhJwffp4iz7IiaOPK33jqkV a0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342 mslabel:LblgNN4d9nhJwffp4iz7IiaOPK33jqkV 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ssrc:3613542342 label:LblgNN4d9nhJwffp4iz7IiaOPK33jqkVa0 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-ufrag:Wbcukd9ZO2TXi3JA 5f9a0e46-ce58-11e3-bc23-179817d66480 a=ice-pwd:qBuqnsqnyZKIDlNq 5f9a0e46-ce58-11e3-bc23-179817d66480 a=candidate:1687001334 1 udp 659136 192.168.1.103 27400 typ host generation 0 5f9a0e46-ce58-11e3-bc23-179817d66480 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [completed][200] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [NOTICE] mod_dptools.c:1232 Channel [sofia/internal/1001@vortex01.no-ip.info] has been answered 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_channel.c:3680 (sofia/internal/1001@vortex01.no-ip.info) Callstate Change EARLY -> ACTIVE 5f9a0e46-ce58-11e3-bc23-179817d66480 EXECUTE sofia/internal/ 1001@vortex01.no-ip.info conference(3000-192.168.1.103@default) 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9366 using channel sound prefix: /usr/local/freeswitch_master/sounds/en/us/callie 2014-04-28 00:07:50.768880 [DEBUG] mod_conference.c:2526 Setup timer success interval: 20 samples: 160 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] mod_conference.c:8052 Raw Codec Activation Success L16@8000hz 1 channel 20ms 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] mod_conference.c:8097 Raw Codec Activation Success L16@8000hz 1 channel 20ms 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_codec.c:221 sofia/internal/1001@vortex01.no-ip.info Push codec L16:70 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '0' to 'mute' 2014-04-28 00:07:50.768880 [INFO] switch_ivr_async.c:212 Digit parser mod_conference: Setting realm to 'conf' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f898a246050 data: 0x7f89940bca00 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '*' to 'deaf mute' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding */conf/0 callback: 0x7f898a246050 data: 0x7f89940bca30 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '9' to 'energy up' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f898a246050 data: 0x7f89940bca60 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '8' to 'energy equ' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f898a246050 data: 0x7f89940bca90 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '7' to 'energy dn' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcac0 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '3' to 'vol talk up' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcaf0 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '2' to 'vol talk zero' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcb20 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '1' to 'vol talk dn' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcb50 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '6' to 'vol listen up' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcb80 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '5' to 'vol listen zero' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcbb0 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '4' to 'vol listen dn' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcbe0 2014-04-28 00:07:50.768880 [INFO] mod_conference.c:9966 sofia/internal/ 1001@vortex01.no-ip.info binding '#' to 'hangup' 2014-04-28 00:07:50.768880 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding #/conf/0 callback: 0x7f898a246050 data: 0x7f89940bcc10 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:50.768880 [DEBUG] mod_conference.c:4146 Setup timer soft success interval: 20 samples: 160 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.888272 [DEBUG] switch_core_session.c:1050 Send signal sofia/internal/ 1001@vortex01.no-ip.info [BREAK] 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:51.908250 [DEBUG] sofia.c:6163 Channel sofia/internal/1001@vortex01.no-ip.info entering state [ready][200] 2014-04-28 00:07:53.528265 [DEBUG] mod_local_stream.c:498 Opening Stream [moh/8000] 8000hz 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO] switch_rtp.c:2701 Changing audio DTLS state from HANDSHAKE to SETUP 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO] switch_rtp.c:2609 audio Fingerprint Verified. 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO] switch_rtp.c:3151 Activating Audio Secure RTP SEND 2014-04-28 00:07:53.788881 [DEBUG] switch_core_sqldb.c:2551 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO] switch_rtp.c:3129 Activating Audio Secure RTP RECV 2014-04-28 00:07:53.788881 [DEBUG] switch_core_sqldb.c:2551 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 5f9a0e46-ce58-11e3-bc23-179817d66480 2014-04-28 00:07:53.788881 [INFO] switch_rtp.c:2649 Changing audio DTLS state from SETUP to READY