Hi all,
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.info Standard 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.info Standard 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 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.info Standard EXECUTE
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 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.info Standard 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 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 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.info Standard EXECUTE
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.info hash(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.info hash(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 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.info PCMU/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:57265 based 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] 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 [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.info Standard 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.info Standard 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 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.info Standard EXECUTE
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 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.info Standard 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 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 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.info Standard EXECUTE
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.info hash(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.info hash(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 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.info PCMU/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:41816 based 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] 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 [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