2013-11-16 15:18:28.815319 [WARNING] sofia_reg.c:1634 SIP auth challenge (REGISTER) on sofia profile 'testing' for [
10701...@125.99.186.124] from ip 113.193.186.78
2013-11-16 15:18:57.595318 [WARNING] sofia_reg.c:1634 SIP auth challenge (REGISTER) on sofia profile 'testing' for [
10701...@125.99.186.124] from ip 113.193.186.78
2013-11-16 15:21:09.135319 [WARNING] sofia_reg.c:1634 SIP auth challenge (REGISTER) on sofia profile 'testing' for [
11632...@125.99.186.124] from ip 113.193.186.78
2013-11-16 15:21:41.975318 [NOTICE] switch_channel.c:1048 New Channel sofia/testing/
11632...@125.99.186.124 [b20aefd0-4ea4-11e3-9c32-7f40b0dc89fc]
2013-11-16 15:21:41.975318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:41.975318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:41.975318 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_NEW
2013-11-16 15:21:41.975318 [DEBUG] switch_core_state_machine.c:485 (sofia/testing/
11632...@125.99.186.124) State NEW
2013-11-16 15:21:41.995322 [DEBUG] sofia.c:8033 IP 113.193.186.78 Rejected by acl "default". Falling back to Digest auth.
2013-11-16 15:21:41.995322 [WARNING] sofia_reg.c:1634 SIP auth challenge (INVITE) on sofia profile 'testing' for [
10701...@125.99.186.124] from ip 113.193.186.78
2013-11-16 15:21:41.995322 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:41.995322 [DEBUG] sofia.c:1816 detaching session b20aefd0-4ea4-11e3-9c32-7f40b0dc89fc
2013-11-16 15:21:42.055318 [DEBUG] sofia.c:1908 Re-attaching to session b20aefd0-4ea4-11e3-9c32-7f40b0dc89fc
2013-11-16 15:21:42.055318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.055318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.055318 [DEBUG] sofia.c:8033 IP 113.193.186.78 Rejected by acl "default". Falling back to Digest auth.
2013-11-16 15:21:42.255346 [DEBUG] sofia.c:9075 Setting NAT mode based on via received
2013-11-16 15:21:42.255346 [DEBUG] sofia.c:5863 Channel sofia/testing/
11632...@125.99.186.124 entering state [received][100]
2013-11-16 15:21:42.255346 [DEBUG] sofia.c:5873 Remote SDP:
v=0
o=- 9 2 IN IP4 192.168.1.6
s=CounterPath X-Lite 3.0
c=IN IP4 192.168.1.6
t=0 0
m=audio 9692 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=alt:1 1 : uDtDA/JQ 4Tr+8LAl 192.168.1.6 9692
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[b64:116:32000:20:684800]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[AMR:96:8000:20:12200]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[SPEEX:99:32000:20:44000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G729:18:8000:20:8000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G726-16:124:8000:20:16000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[DVI4:6:16000:20:64000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G726-24:123:8000:20:24000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:1956 Set Codec sofia/testing/
11632...@125.99.186.124 PCMU/8000 20 ms 160 samples 64000 bits
2013-11-16 15:21:42.255346 [DEBUG] switch_core_codec.c:111 sofia/testing/
11632...@125.99.186.124 Original read codec set to PCMU:0
2013-11-16 15:21:42.255346 [DEBUG] switch_core_media.c:3187 Set 2833 dtmf send/recv payload to 101
2013-11-16 15:21:42.255346 [DEBUG] sofia.c:6105 (sofia/testing/
11632...@125.99.186.124) State Change CS_NEW -> CS_INIT
2013-11-16 15:21:42.255346 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_INIT
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:506 (sofia/testing/
11632...@125.99.186.124) State INIT
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:40 sofia/testing/
11632...@125.99.186.124 Standard INIT
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:48 (sofia/testing/
11632...@125.99.186.124) State Change CS_INIT -> CS_ROUTING
2013-11-16 15:21:42.255346 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:506 (sofia/testing/
11632...@125.99.186.124) State INIT going to sleep
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_ROUTING
2013-11-16 15:21:42.255346 [DEBUG] switch_channel.c:2140 (sofia/testing/
11632...@125.99.186.124) Callstate Change DOWN -> RINGING
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
11632...@125.99.186.124) State ROUTING
2013-11-16 15:21:42.255346 [DEBUG] switch_core_state_machine.c:164 sofia/testing/
11632...@125.99.186.124 Standard ROUTING
2013-11-16 15:21:42.255346 [INFO] mod_dialplan_xml.c:558 Processing 1163250936 <1163250936>->1070125501 in context default
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [global-intercept] destination_number(1070125501) =~ /^886$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [group-intercept] destination_number(1070125501) =~ /^\*8$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [intercept-ext] destination_number(1070125501) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [redial] destination_number(1070125501) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [global] ${endpoint_disposition}(RECEIVED) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [snom-demo-2] destination_number(1070125501) =~ /^9001$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [snom-demo-1] destination_number(1070125501) =~ /^9000$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [eavesdrop] destination_number(1070125501) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [eavesdrop] destination_number(1070125501) =~ /^779$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [call_return] destination_number(1070125501) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [del-group] destination_number(1070125501) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [add-group] destination_number(1070125501) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [call-group-simo] destination_number(1070125501) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [call-group-order] destination_number(1070125501) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [extension-intercom] destination_number(1070125501) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [Local_Extension] destination_number(1070125501) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [Local_Extension_Skinny] destination_number(1070125501) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [group_dial_sales] destination_number(1070125501) =~ /^2000$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [group_dial_support] destination_number(1070125501) =~ /^2001$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [group_dial_billing] destination_number(1070125501) =~ /^2002$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [operator] destination_number(1070125501) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [vmain] destination_number(1070125501) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [sip_uri] destination_number(1070125501) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [nb_conferences] destination_number(1070125501) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [wb_conferences] destination_number(1070125501) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [uwb_conferences] destination_number(1070125501) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [cdquality_conferences] destination_number(1070125501) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(1070125501) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [mad_boss_intercom] destination_number(1070125501) =~ /^0911$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [mad_boss_intercom] destination_number(1070125501) =~ /^0912$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [mad_boss] destination_number(1070125501) =~ /^0913$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ivr_demo] destination_number(1070125501) =~ /^5000$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [dynamic_conference] destination_number(1070125501) =~ /^5001$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [rtp_multicast_page] destination_number(1070125501) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [park] destination_number(1070125501) =~ /^5900$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1070125501) =~ /^5901$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [valet_park] destination_number(1070125501) =~ /^(6000)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [valet_park] destination_number(1070125501) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [park] destination_number(1070125501) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1070125501) =~ /^parking$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [park] destination_number(1070125501) =~ /callpark/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1070125501) =~ /pickup/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [wait] destination_number(1070125501) =~ /^wait$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [fax_receive] destination_number(1070125501) =~ /^9178$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [fax_transmit] destination_number(1070125501) =~ /^9179$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ringback_180] destination_number(1070125501) =~ /^9180$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ringback_183_uk_ring] destination_number(1070125501) =~ /^9181$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ringback_183_music_ring] destination_number(1070125501) =~ /^9182$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(1070125501) =~ /^9183$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ringback_post_answer_music] destination_number(1070125501) =~ /^9184$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [ClueCon] destination_number(1070125501) =~ /^9191$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [show_info] destination_number(1070125501) =~ /^9192$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [video_record] destination_number(1070125501) =~ /^9193$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [video_playback] destination_number(1070125501) =~ /^9194$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [delay_echo] destination_number(1070125501) =~ /^9195$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [echo] destination_number(1070125501) =~ /^9196$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [milliwatt] destination_number(1070125501) =~ /^9197$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [tone_stream] destination_number(1070125501) =~ /^9198$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [zrtp_enrollement] destination_number(1070125501) =~ /^9787$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [hold_music] destination_number(1070125501) =~ /^9664$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [laugh break] destination_number(1070125501) =~ /^9386$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [101] destination_number(1070125501) =~ /^101$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [pizza_demo] destination_number(1070125501) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [Talking Clock Time] destination_number(1070125501) =~ /^9170$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [Talking Clock Date] destination_number(1070125501) =~ /^9171$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [Talking Clock Date and Time] destination_number(1070125501) =~ /^9172$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (FAIL) [astpp_callingcards] destination_number(1070125501) =~ /^8008018888$/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [enum] ${module_exists(mod_enum)}(true) =~ /true/ break=on-false
Dialplan: sofia/testing/
11632...@125.99.186.124 Regex (PASS) [enum] destination_number(1070125501) =~ /^(.*)$/ break=on-false
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:214 (sofia/testing/
11632...@125.99.186.124) State Change CS_ROUTING -> CS_EXECUTE
2013-11-16 15:21:42.535326 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
11632...@125.99.186.124) State ROUTING going to sleep
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_EXECUTE
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
11632...@125.99.186.124) State EXECUTE
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:256 sofia/testing/
11632...@125.99.186.124 Standard EXECUTE
2013-11-16 15:21:42.535326 [DEBUG] switch_ivr.c:1824 (sofia/testing/
11632...@125.99.186.124) State Change CS_EXECUTE -> CS_ROUTING
2013-11-16 15:21:42.535326 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.535326 [DEBUG] switch_core_session.c:892 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.535326 [NOTICE] switch_ivr.c:1831 Transfer sofia/testing/
11632...@125.99.186.124 to enum[1070125501@default]
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
11632...@125.99.186.124) State EXECUTE going to sleep
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_ROUTING
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
11632...@125.99.186.124) State ROUTING
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:164 sofia/testing/
11632...@125.99.186.124 Standard ROUTING
2013-11-16 15:21:42.535326 [DEBUG] mod_enum.c:642 ENUM Lookup on 1070125501
2013-11-16 15:21:42.535326 [DEBUG] mod_enum.c:494 No Nameservers specified, using host default
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:214 (sofia/testing/
11632...@125.99.186.124) State Change CS_ROUTING -> CS_EXECUTE
2013-11-16 15:21:42.935319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
11632...@125.99.186.124) State ROUTING going to sleep
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_EXECUTE
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
11632...@125.99.186.124) State EXECUTE
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:256 sofia/testing/
11632...@125.99.186.124 Standard EXECUTE
2013-11-16 15:21:42.935319 [NOTICE] switch_core_state_machine.c:313 sofia/testing/
11632...@125.99.186.124 has executed the last dialplan instruction, hanging up.
2013-11-16 15:21:42.935319 [NOTICE] switch_core_state_machine.c:315 Hangup sofia/testing/
11632...@125.99.186.124 [CS_EXECUTE] [NORMAL_CLEARING]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
11632...@125.99.186.124) State EXECUTE going to sleep
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_HANGUP
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:730 (sofia/testing/
11632...@125.99.186.124) State HANGUP
2013-11-16 15:21:42.935319 [DEBUG] mod_sofia.c:413 Channel sofia/testing/
11632...@125.99.186.124 hanging up, cause: NORMAL_CLEARING
2013-11-16 15:21:42.935319 [DEBUG] mod_sofia.c:547 Responding to INVITE with: 480
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:58 sofia/testing/
11632...@125.99.186.124 Standard HANGUP, cause: NORMAL_CLEARING
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:730 (sofia/testing/
11632...@125.99.186.124) State HANGUP going to sleep
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:743 (sofia/testing/
11632...@125.99.186.124) Callstate Change RINGING -> HANGUP
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:498 (sofia/testing/
11632...@125.99.186.124) State Change CS_HANGUP -> CS_REPORTING
2013-11-16 15:21:42.935319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_REPORTING
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:815 (sofia/testing/
11632...@125.99.186.124) State REPORTING
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:102 sofia/testing/
11632...@125.99.186.124 Standard REPORTING, cause: NORMAL_CLEARING
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:815 (sofia/testing/
11632...@125.99.186.124) State REPORTING going to sleep
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:492 (sofia/testing/
11632...@125.99.186.124) State Change CS_REPORTING -> CS_DESTROY
2013-11-16 15:21:42.935319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
11632...@125.99.186.124 [BREAK]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_session.c:1580 Session 9 (sofia/testing/
11632...@125.99.186.124) Locked, Waiting on external entities
2013-11-16 15:21:42.935319 [NOTICE] switch_core_session.c:1598 Session 9 (sofia/testing/
11632...@125.99.186.124) Ended
2013-11-16 15:21:42.935319 [NOTICE] switch_core_session.c:1602 Close Channel sofia/testing/
11632...@125.99.186.124 [CS_DESTROY]
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:617 (sofia/testing/
11632...@125.99.186.124) Callstate Change HANGUP -> DOWN
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:620 (sofia/testing/
11632...@125.99.186.124) Running State Change CS_DESTROY
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:630 (sofia/testing/
11632...@125.99.186.124) State DESTROY
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:109 sofia/testing/
11632...@125.99.186.124 Standard DESTROY
2013-11-16 15:21:42.935319 [DEBUG] switch_core_state_machine.c:630 (sofia/testing/
11632...@125.99.186.124) State DESTROY going to sleep
2013-11-16 15:29:03.295319 [NOTICE] switch_channel.c:1048 New Channel sofia/testing/
10701...@125.99.186.124 [b915d7bc-4ea5-11e3-9c55-7f40b0dc89fc]
2013-11-16 15:29:03.295319 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.295319 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.295319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_NEW
2013-11-16 15:29:03.295319 [DEBUG] switch_core_state_machine.c:485 (sofia/testing/
10701...@125.99.186.124) State NEW
2013-11-16 15:29:03.315322 [DEBUG] sofia.c:8033 IP 113.193.186.78 Rejected by acl "default". Falling back to Digest auth.
2013-11-16 15:29:03.315322 [WARNING] sofia_reg.c:1634 SIP auth challenge (INVITE) on sofia profile 'testing' for [
11632...@125.99.186.124] from ip 113.193.186.78
2013-11-16 15:29:03.315322 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.315322 [DEBUG] sofia.c:1816 detaching session b915d7bc-4ea5-11e3-9c55-7f40b0dc89fc
2013-11-16 15:29:03.395318 [DEBUG] sofia.c:1908 Re-attaching to session b915d7bc-4ea5-11e3-9c55-7f40b0dc89fc
2013-11-16 15:29:03.395318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.395318 [DEBUG] switch_core_session.c:1037 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.415321 [DEBUG] sofia.c:8033 IP 113.193.186.78 Rejected by acl "default". Falling back to Digest auth.
2013-11-16 15:29:03.615348 [DEBUG] sofia.c:9075 Setting NAT mode based on via received
2013-11-16 15:29:03.615348 [DEBUG] sofia.c:5863 Channel sofia/testing/
10701...@125.99.186.124 entering state [received][100]
2013-11-16 15:29:03.615348 [DEBUG] sofia.c:5873 Remote SDP:
v=0
o=- 7 2 IN IP4 192.168.1.20
s=CounterPath X-Lite 3.0
c=IN IP4 192.168.1.20
t=0 0
m=audio 30402 RTP/AVP 107 119 100 106 0 105 98 8 101
a=rtpmap:107 BV32/16000
a=rtpmap:119 BV32-FEC/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=alt:1 1 : MX2tO5Af rvNPIFVu 192.168.1.20 30402
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[b64:116:32000:20:684800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[AMR:96:8000:20:12200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[SPEEX:99:32000:20:44000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G729:18:8000:20:8000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[PCMA:8:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[DVI4:6:16000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[PCMU:0:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[L16:70:22050:20:352800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[PROXY:0:8000:20:0]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[AAL2-G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[AAL2-G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G722:9:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[AAL2-G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[GSM:3:8000:20:13200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[G723:4:8000:30:6300]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32:107:16000:20:0]/[LPC:7:8000:90:2400]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[b64:116:32000:20:684800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[AMR:96:8000:20:12200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[SPEEX:99:32000:20:44000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G729:18:8000:20:8000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[PCMA:8:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[DVI4:6:16000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[PCMU:0:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[L16:70:22050:20:352800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[PROXY:0:8000:20:0]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[AAL2-G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[AAL2-G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G722:9:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[AAL2-G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[GSM:3:8000:20:13200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[G723:4:8000:30:6300]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [BV32-FEC:119:16000:20:0]/[LPC:7:8000:90:2400]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[b64:116:32000:20:684800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[AMR:96:8000:20:12200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[SPEEX:99:32000:20:44000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G729:18:8000:20:8000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[PCMA:8:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[DVI4:6:16000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[PCMU:0:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[L16:70:22050:20:352800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[PROXY:0:8000:20:0]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[AAL2-G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[AAL2-G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G722:9:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[AAL2-G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[GSM:3:8000:20:13200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[G723:4:8000:30:6300]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX:100:16000:20:0]/[LPC:7:8000:90:2400]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[b64:116:32000:20:684800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[AMR:96:8000:20:12200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[SPEEX:99:32000:20:44000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G729:18:8000:20:8000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[PCMA:8:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[DVI4:6:16000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[PCMU:0:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[L16:70:22050:20:352800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[PROXY:0:8000:20:0]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[AAL2-G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[AAL2-G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G726-32:122:8000:20:32000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G722:9:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[AAL2-G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G726-40:121:8000:20:40000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[GSM:3:8000:20:13200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[G723:4:8000:30:6300]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [SPEEX-FEC:106:16000:20:0]/[LPC:7:8000:90:2400]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[b64:116:32000:20:684800]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[AMR:96:8000:20:12200]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[SPEEX:99:32000:20:44000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G729:18:8000:20:8000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[AAL2-G726-16:124:8000:20:16000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[DVI4:6:16000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[G726-24:123:8000:20:24000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:2997 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:1956 Set Codec sofia/testing/
10701...@125.99.186.124 PCMU/8000 20 ms 160 samples 64000 bits
2013-11-16 15:29:03.615348 [DEBUG] switch_core_codec.c:111 sofia/testing/
10701...@125.99.186.124 Original read codec set to PCMU:0
2013-11-16 15:29:03.615348 [DEBUG] switch_core_media.c:3187 Set 2833 dtmf send/recv payload to 101
2013-11-16 15:29:03.615348 [DEBUG] sofia.c:6105 (sofia/testing/
10701...@125.99.186.124) State Change CS_NEW -> CS_INIT
2013-11-16 15:29:03.615348 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_INIT
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:506 (sofia/testing/
10701...@125.99.186.124) State INIT
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:40 sofia/testing/
10701...@125.99.186.124 Standard INIT
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:48 (sofia/testing/
10701...@125.99.186.124) State Change CS_INIT -> CS_ROUTING
2013-11-16 15:29:03.615348 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:506 (sofia/testing/
10701...@125.99.186.124) State INIT going to sleep
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_ROUTING
2013-11-16 15:29:03.615348 [DEBUG] switch_channel.c:2140 (sofia/testing/
10701...@125.99.186.124) Callstate Change DOWN -> RINGING
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
10701...@125.99.186.124) State ROUTING
2013-11-16 15:29:03.615348 [DEBUG] switch_core_state_machine.c:164 sofia/testing/
10701...@125.99.186.124 Standard ROUTING
2013-11-16 15:29:03.615348 [INFO] mod_dialplan_xml.c:558 Processing 1070125501 <1070125501>->1163250936 in context default
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [global-intercept] destination_number(1163250936) =~ /^886$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [group-intercept] destination_number(1163250936) =~ /^\*8$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [intercept-ext] destination_number(1163250936) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [redial] destination_number(1163250936) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [global] ${endpoint_disposition}(RECEIVED) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [snom-demo-2] destination_number(1163250936) =~ /^9001$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [snom-demo-1] destination_number(1163250936) =~ /^9000$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [eavesdrop] destination_number(1163250936) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [eavesdrop] destination_number(1163250936) =~ /^779$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [call_return] destination_number(1163250936) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [del-group] destination_number(1163250936) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [add-group] destination_number(1163250936) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [call-group-simo] destination_number(1163250936) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [call-group-order] destination_number(1163250936) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [extension-intercom] destination_number(1163250936) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [Local_Extension] destination_number(1163250936) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [Local_Extension_Skinny] destination_number(1163250936) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [group_dial_sales] destination_number(1163250936) =~ /^2000$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [group_dial_support] destination_number(1163250936) =~ /^2001$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [group_dial_billing] destination_number(1163250936) =~ /^2002$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [operator] destination_number(1163250936) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [vmain] destination_number(1163250936) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [sip_uri] destination_number(1163250936) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [nb_conferences] destination_number(1163250936) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [wb_conferences] destination_number(1163250936) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [uwb_conferences] destination_number(1163250936) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [cdquality_conferences] destination_number(1163250936) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(1163250936) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [mad_boss_intercom] destination_number(1163250936) =~ /^0911$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [mad_boss_intercom] destination_number(1163250936) =~ /^0912$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [mad_boss] destination_number(1163250936) =~ /^0913$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ivr_demo] destination_number(1163250936) =~ /^5000$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [dynamic_conference] destination_number(1163250936) =~ /^5001$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [rtp_multicast_page] destination_number(1163250936) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [park] destination_number(1163250936) =~ /^5900$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1163250936) =~ /^5901$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [valet_park] destination_number(1163250936) =~ /^(6000)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [valet_park] destination_number(1163250936) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [park] destination_number(1163250936) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1163250936) =~ /^parking$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [park] destination_number(1163250936) =~ /callpark/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [unpark] destination_number(1163250936) =~ /pickup/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [wait] destination_number(1163250936) =~ /^wait$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [fax_receive] destination_number(1163250936) =~ /^9178$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [fax_transmit] destination_number(1163250936) =~ /^9179$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ringback_180] destination_number(1163250936) =~ /^9180$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ringback_183_uk_ring] destination_number(1163250936) =~ /^9181$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ringback_183_music_ring] destination_number(1163250936) =~ /^9182$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(1163250936) =~ /^9183$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ringback_post_answer_music] destination_number(1163250936) =~ /^9184$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [ClueCon] destination_number(1163250936) =~ /^9191$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [show_info] destination_number(1163250936) =~ /^9192$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [video_record] destination_number(1163250936) =~ /^9193$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [video_playback] destination_number(1163250936) =~ /^9194$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [delay_echo] destination_number(1163250936) =~ /^9195$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [echo] destination_number(1163250936) =~ /^9196$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [milliwatt] destination_number(1163250936) =~ /^9197$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [tone_stream] destination_number(1163250936) =~ /^9198$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [zrtp_enrollement] destination_number(1163250936) =~ /^9787$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [hold_music] destination_number(1163250936) =~ /^9664$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [laugh break] destination_number(1163250936) =~ /^9386$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [101] destination_number(1163250936) =~ /^101$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [pizza_demo] destination_number(1163250936) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [Talking Clock Time] destination_number(1163250936) =~ /^9170$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [Talking Clock Date] destination_number(1163250936) =~ /^9171$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [Talking Clock Date and Time] destination_number(1163250936) =~ /^9172$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (FAIL) [astpp_callingcards] destination_number(1163250936) =~ /^8008018888$/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [enum] ${module_exists(mod_enum)}(true) =~ /true/ break=on-false
Dialplan: sofia/testing/
10701...@125.99.186.124 Regex (PASS) [enum] destination_number(1163250936) =~ /^(.*)$/ break=on-false
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:214 (sofia/testing/
10701...@125.99.186.124) State Change CS_ROUTING -> CS_EXECUTE
2013-11-16 15:29:03.875319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
10701...@125.99.186.124) State ROUTING going to sleep
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_EXECUTE
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
10701...@125.99.186.124) State EXECUTE
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:256 sofia/testing/
10701...@125.99.186.124 Standard EXECUTE
2013-11-16 15:29:03.875319 [DEBUG] switch_ivr.c:1824 (sofia/testing/
10701...@125.99.186.124) State Change CS_EXECUTE -> CS_ROUTING
2013-11-16 15:29:03.875319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.875319 [DEBUG] switch_core_session.c:892 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:03.875319 [NOTICE] switch_ivr.c:1831 Transfer sofia/testing/
10701...@125.99.186.124 to enum[1163250936@default]
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
10701...@125.99.186.124) State EXECUTE going to sleep
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_ROUTING
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
10701...@125.99.186.124) State ROUTING
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:164 sofia/testing/
10701...@125.99.186.124 Standard ROUTING
2013-11-16 15:29:03.875319 [DEBUG] mod_enum.c:642 ENUM Lookup on 1163250936
2013-11-16 15:29:03.875319 [DEBUG] mod_enum.c:494 No Nameservers specified, using host default
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:214 (sofia/testing/
10701...@125.99.186.124) State Change CS_ROUTING -> CS_EXECUTE
2013-11-16 15:29:04.275319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:522 (sofia/testing/
10701...@125.99.186.124) State ROUTING going to sleep
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_EXECUTE
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
10701...@125.99.186.124) State EXECUTE
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:256 sofia/testing/
10701...@125.99.186.124 Standard EXECUTE
2013-11-16 15:29:04.275319 [NOTICE] switch_core_state_machine.c:313 sofia/testing/
10701...@125.99.186.124 has executed the last dialplan instruction, hanging up.
2013-11-16 15:29:04.275319 [NOTICE] switch_core_state_machine.c:315 Hangup sofia/testing/
10701...@125.99.186.124 [CS_EXECUTE] [NORMAL_CLEARING]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:529 (sofia/testing/
10701...@125.99.186.124) State EXECUTE going to sleep
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_HANGUP
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:730 (sofia/testing/
10701...@125.99.186.124) State HANGUP
2013-11-16 15:29:04.275319 [DEBUG] mod_sofia.c:413 Channel sofia/testing/
10701...@125.99.186.124 hanging up, cause: NORMAL_CLEARING
2013-11-16 15:29:04.275319 [DEBUG] mod_sofia.c:547 Responding to INVITE with: 480
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:58 sofia/testing/
10701...@125.99.186.124 Standard HANGUP, cause: NORMAL_CLEARING
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:730 (sofia/testing/
10701...@125.99.186.124) State HANGUP going to sleep
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:743 (sofia/testing/
10701...@125.99.186.124) Callstate Change RINGING -> HANGUP
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:498 (sofia/testing/
10701...@125.99.186.124) State Change CS_HANGUP -> CS_REPORTING
2013-11-16 15:29:04.275319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:467 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_REPORTING
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:815 (sofia/testing/
10701...@125.99.186.124) State REPORTING
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:102 sofia/testing/
10701...@125.99.186.124 Standard REPORTING, cause: NORMAL_CLEARING
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:815 (sofia/testing/
10701...@125.99.186.124) State REPORTING going to sleep
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:492 (sofia/testing/
10701...@125.99.186.124) State Change CS_REPORTING -> CS_DESTROY
2013-11-16 15:29:04.275319 [DEBUG] switch_core_session.c:1372 Send signal sofia/testing/
10701...@125.99.186.124 [BREAK]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_session.c:1580 Session 10 (sofia/testing/
10701...@125.99.186.124) Locked, Waiting on external entities
2013-11-16 15:29:04.275319 [NOTICE] switch_core_session.c:1598 Session 10 (sofia/testing/
10701...@125.99.186.124) Ended
2013-11-16 15:29:04.275319 [NOTICE] switch_core_session.c:1602 Close Channel sofia/testing/
10701...@125.99.186.124 [CS_DESTROY]
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:617 (sofia/testing/
10701...@125.99.186.124) Callstate Change HANGUP -> DOWN
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:620 (sofia/testing/
10701...@125.99.186.124) Running State Change CS_DESTROY
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:630 (sofia/testing/
10701...@125.99.186.124) State DESTROY
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:109 sofia/testing/
10701...@125.99.186.124 Standard DESTROY
2013-11-16 15:29:04.275319 [DEBUG] switch_core_state_machine.c:630 (sofia/testing/
10701...@125.99.186.124) State DESTROY going to sleep