how to integrate a clicktocall solution with astpp

238 views
Skip to first unread message

mohammed shafeeque

unread,
Oct 25, 2013, 11:51:09 AM10/25/13
to as...@googlegroups.com
Hello All

I was trying to develop a clicktocall solution with freeswitch and billing the calls with astpp. So i planned to use the event socket library with the originate application in freeswitch and it works perfectly without the billing of calls. Is there any way i can integrate this to astpp?

Regards

Samir Doshi

unread,
Oct 29, 2013, 10:52:14 AM10/29/13
to as...@googlegroups.com
Hi Mohammed,

Interesting!!
Okay here is some explaination, ASTPP call scripts needs accountcode
variable to authenticate calls. So, if you can pass
accountcode/accountnumber of customer and context = default then that
should work.

Please give a try and let me know.


Thanks,
Samir Doshi
ASTPP - Open Source VoIP Billing Solution
> --
> You received this message because you are subscribed to the Google
> Groups "ASTPP" group.
> To unsubscribe from this group and stop receiving emails from it, send
> an email to astpp+un...@googlegroups.com.
> To post to this group, send email to as...@googlegroups.com.
> Visit this group at http://groups.google.com/group/astpp.
> To view this discussion on the web visit
> https://groups.google.com/d/msgid/astpp/0b9d2d82-7af1-4b56-8c9e-276436f9d45f%40googlegroups.com.
> For more options, visit https://groups.google.com/groups/opt_out.

mohammed shafeeque

unread,
Oct 29, 2013, 12:45:04 PM10/29/13
to as...@googlegroups.com
Thanks for the tip samir, its working fine !!!

I send the calls from freeswitch cli with command originate {ignore_early_media=true,accountcode=6712611427}sofia/gateway/trunk1/91xxxxxxxxxx xxxxxxxxxx and the calls gets billed in astpp under the account 6712611427

Thanks again....

Sandeep Rawat

unread,
Oct 31, 2013, 11:22:36 PM10/31/13
to as...@googlegroups.com
Hello Mr. Samir Doshi 

Sir, I will complete astpp and freeswitch installation. after that when I will register astpp account in our softphone then facing error "Register error:  403 forbidden".

Please help me solve this issue.

Thanks & Regards,
Sandeep Rawat.

Samir Doshi

unread,
Nov 6, 2013, 1:36:41 AM11/6/13
to as...@googlegroups.com
Hi,

Please check below settings,

1. Check if mod_xml_curl module loaded or not
2. Make sure you are using correct username and password of sip device.

If everything seems correct then please send us log.


Thanks,
Samir Doshi
ASTPP - Open Source VoIP Billing Solution
--
You received this message because you are subscribed to the Google Groups "ASTPP" group.
To unsubscribe from this group and stop receiving emails from it, send an email to astpp+un...@googlegroups.com.
To post to this group, send email to as...@googlegroups.com.
Visit this group at http://groups.google.com/group/astpp.
Message has been deleted

Sandeep Rawat

unread,
Nov 12, 2013, 11:06:10 PM11/12/13
to as...@googlegroups.com, sa...@astpp.org
Hello sir,

Thanks for helping. but my issue is not resolved. when I will try to registered softphone then this log is generated. 

please check my logs :-

/usr/local/freeswitch/log/freeswitch.log :-

2013-11-13 09:24:59.317731 [WARNING] sofia_reg.c:2682 Can't find user [11128...@125.99.186.124] from 113.193.186.78
You must define a domain called '125.99.186.124' in your directory and add a user with the id="1112838358" attribute
and you must configure your device to use the proper domain in it's authentication credentials.

/var/log/http/astpp_error_log :-

[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Can't locate Locale/gettext_pp.pm in @INC (@INC contains: /usr/local/lib/perl5 /usr/local/share/perl5 /usr/lib/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib/perl5 /usr/share/perl5 .) at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] BEGIN failed--compilation aborted at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Compilation failed in require at /var/www/cgi-bin/astpp/astpp-fs-xml.cgi line 22.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Premature end of script headers: astpp-fs-xml.cgi
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Premature end of script headers: astpp-fs-xml.cgi
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Can't locate Locale/gettext_pp.pm in @INC (@INC contains: /usr/local/lib/perl5 /usr/local/share/perl5 /usr/lib/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib/perl5 /usr/share/perl5 .) at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] BEGIN failed--compilation aborted at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Compilation failed in require at /var/www/cgi-bin/astpp/astpp-fs-xml.cgi line 22.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Premature end of script headers: astpp-fs-xml.cgi
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Can't locate Locale/gettext_pp.pm in @INC (@INC contains: /usr/local/lib/perl5 /usr/local/share/perl5 /usr/lib/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib/perl5 /usr/share/perl5 .) at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] BEGIN failed--compilation aborted at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Compilation failed in require at /var/www/cgi-bin/astpp/astpp-fs-xml.cgi line 22.
[Tue Nov 12 08:27:30 2013] [error] [client 125.99.186.124] Premature end of script headers: astpp-fs-xml.cgi
[Tue Nov 12 08:27:34 2013] [error] [client 125.99.186.124] Can't locate Locale/gettext_pp.pm in @INC (@INC contains: /usr/local/lib/perl5 /usr/local/share/perl5 /usr/lib/perl5/vendor_perl /usr/share/perl5/vendor_perl /usr/lib/perl5 /usr/share/perl5 .) at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:34 2013] [error] [client 125.99.186.124] BEGIN failed--compilation aborted at /usr/local/astpp/astpp-common.pl line 18.
[Tue Nov 12 08:27:34 2013] [error] [client 125.99.186.124] Compilation failed in require at /var/www/cgi-bin/astpp/astpp-fs-xml.cgi line 22.
[Tue Nov 12 08:27:34 2013] [error] [client 125.99.186.124] Premature end of script headers: astpp-fs-xml.cgi

http error log:-

[Tue Nov 12 08:15:30 2013] [notice] caught SIGTERM, shutting down
[Tue Nov 12 08:15:31 2013] [notice] Digest: generating secret for digest authentication ...
[Tue Nov 12 08:15:31 2013] [notice] Digest: done
[Tue Nov 12 08:15:32 2013] [error] avahi_entry_group_add_service_strlst("125.99.186.124") failed: Invalid host name
[Tue Nov 12 08:15:32 2013] [notice] Apache/2.2.15 (Unix) DAV/2 PHP/5.3.3 mod_perl/2.0.4 Perl/v5.10.1 configured -- resuming normal operations
[Tue Nov 12 08:25:59 2013] [notice] caught SIGTERM, shutting down
[Tue Nov 12 08:25:59 2013] [notice] Digest: generating secret for digest authentication ...
[Tue Nov 12 08:25:59 2013] [notice] Digest: done
[Tue Nov 12 08:25:59 2013] [error] avahi_entry_group_add_service_strlst("125.99.186.124") failed: Invalid host name
[Tue Nov 12 08:25:59 2013] [notice] Apache/2.2.15 (Unix) DAV/2 PHP/5.3.3 mod_perl/2.0.4 Perl/v5.10.1 configured -- resuming normal operations
[Tue Nov 12 08:38:56 2013] [error] [client 146.185.165.8] Directory index forbidden by Options directive: /var/www/html/

When i will try to register softphone then some time error is : 403 forbidden, 503 service unavailable and 408 requested timeout.

Please help me sir.

Thanks and Regards,
Sandeep Rawat

Sandeep Rawat

unread,
Nov 16, 2013, 5:36:45 AM11/16/13
to as...@googlegroups.com, sa...@astpp.org
Dear Sir,

Now my softphone is registered but call is not going. when I will try to call then  error is  "Temporarily unavailable".


freeswitch log is :- 

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] mod_sofia.c:87 sofia/testing/11632...@125.99.186.124 SOFIA 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] mod_sofia.c:123 sofia/testing/11632...@125.99.186.124 SOFIA 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 parsing [default->unloop] continue=false
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 parsing [default->tod_example] continue=true
Dialplan: sofia/testing/11632...@125.99.186.124 Date/TimeMatch (FAIL) [tod_example] break=on-false
Dialplan: sofia/testing/11632...@125.99.186.124 parsing [default->holiday_example] continue=true
Dialplan: sofia/testing/11632...@125.99.186.124 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/testing/11632...@125.99.186.124 parsing [default->global-intercept] continue=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 parsing [default->group-intercept] continue=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 parsing [default->intercept-ext] continue=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 parsing [default->redial] continue=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 parsing [default->global] continue=true
Dialplan: sofia/testing/11632...@125.99.186.124 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
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 parsing [default->snom-demo-2] continue=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 parsing [default->snom-demo-1] continue=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 parsing [default->eavesdrop] continue=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 parsing [default->eavesdrop] continue=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 parsing [default->call_return] continue=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 parsing [default->del-group] continue=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 parsing [default->add-group] continue=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 parsing [default->call-group-simo] continue=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 parsing [default->call-group-order] continue=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 parsing [default->extension-intercom] continue=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 parsing [default->Local_Extension] continue=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 parsing [default->Local_Extension_Skinny] continue=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 parsing [default->group_dial_sales] continue=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 parsing [default->group_dial_support] continue=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 parsing [default->group_dial_billing] continue=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 parsing [default->operator] continue=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 parsing [default->vmain] continue=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 parsing [default->sip_uri] continue=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 parsing [default->nb_conferences] continue=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 parsing [default->wb_conferences] continue=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 parsing [default->uwb_conferences] continue=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 parsing [default->cdquality_conferences] continue=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 parsing [default->freeswitch_public_conf_via_sip] continue=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 parsing [default->mad_boss_intercom] continue=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 parsing [default->mad_boss_intercom] continue=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 parsing [default->mad_boss] continue=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 parsing [default->ivr_demo] continue=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 parsing [default->dynamic_conference] continue=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 parsing [default->rtp_multicast_page] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->valet_park] continue=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 parsing [default->valet_park] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->wait] continue=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 parsing [default->fax_receive] continue=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 parsing [default->fax_transmit] continue=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 parsing [default->ringback_180] continue=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 parsing [default->ringback_183_uk_ring] continue=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 parsing [default->ringback_183_music_ring] continue=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 parsing [default->ringback_post_answer_uk_ring] continue=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 parsing [default->ringback_post_answer_music] continue=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 parsing [default->ClueCon] continue=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 parsing [default->show_info] continue=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 parsing [default->video_record] continue=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 parsing [default->video_playback] continue=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 parsing [default->delay_echo] continue=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 parsing [default->echo] continue=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 parsing [default->milliwatt] continue=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 parsing [default->tone_stream] continue=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 parsing [default->zrtp_enrollement] continue=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 parsing [default->hold_music] continue=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 parsing [default->laugh break] continue=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 parsing [default->101] continue=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 parsing [default->pizza_demo] continue=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 parsing [default->local.example.com] continue=false
Dialplan: sofia/testing/11632...@125.99.186.124 Regex (FAIL) [local.example.com] ${toll_allow}() =~ /local/ break=on-false
Dialplan: sofia/testing/11632...@125.99.186.124 parsing [default->domestic.example.com] continue=false
Dialplan: sofia/testing/11632...@125.99.186.124 Regex (FAIL) [domestic.example.com] ${toll_allow}() =~ /domestic/ break=on-false
Dialplan: sofia/testing/11632...@125.99.186.124 parsing [default->international.example.com] continue=false
Dialplan: sofia/testing/11632...@125.99.186.124 Regex (FAIL) [international.example.com] ${toll_allow}() =~ /international/ break=on-false
Dialplan: sofia/testing/11632...@125.99.186.124 parsing [default->Talking Clock Time] continue=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 parsing [default->Talking Clock Date] continue=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 parsing [default->Talking Clock Date and Time] continue=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 parsing [default->astpp_callingcards] continue=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 parsing [default->enum] continue=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
Dialplan: sofia/testing/11632...@125.99.186.124 Action transfer(1070125501 enum) 
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] mod_sofia.c:178 sofia/testing/11632...@125.99.186.124 SOFIA EXECUTE
2013-11-16 15:21:42.535326 [DEBUG] switch_core_state_machine.c:256 sofia/testing/11632...@125.99.186.124 Standard EXECUTE
EXECUTE sofia/testing/11632...@125.99.186.124 transfer(1070125501 enum)
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] mod_sofia.c:123 sofia/testing/11632...@125.99.186.124 SOFIA 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] mod_sofia.c:178 sofia/testing/11632...@125.99.186.124 SOFIA 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_channel.c:3171 Send signal sofia/testing/11632...@125.99.186.124 [KILL]
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] mod_sofia.c:323 sofia/testing/11632...@125.99.186.124 SOFIA 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] mod_sofia.c:87 sofia/testing/10701...@125.99.186.124 SOFIA 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] mod_sofia.c:123 sofia/testing/10701...@125.99.186.124 SOFIA 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 parsing [default->unloop] continue=false
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 parsing [default->tod_example] continue=true
Dialplan: sofia/testing/10701...@125.99.186.124 Date/TimeMatch (FAIL) [tod_example] break=on-false
Dialplan: sofia/testing/10701...@125.99.186.124 parsing [default->holiday_example] continue=true
Dialplan: sofia/testing/10701...@125.99.186.124 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/testing/10701...@125.99.186.124 parsing [default->global-intercept] continue=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 parsing [default->group-intercept] continue=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 parsing [default->intercept-ext] continue=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 parsing [default->redial] continue=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 parsing [default->global] continue=true
Dialplan: sofia/testing/10701...@125.99.186.124 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
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 parsing [default->snom-demo-2] continue=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 parsing [default->snom-demo-1] continue=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 parsing [default->eavesdrop] continue=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 parsing [default->eavesdrop] continue=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 parsing [default->call_return] continue=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 parsing [default->del-group] continue=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 parsing [default->add-group] continue=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 parsing [default->call-group-simo] continue=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 parsing [default->call-group-order] continue=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 parsing [default->extension-intercom] continue=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 parsing [default->Local_Extension] continue=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 parsing [default->Local_Extension_Skinny] continue=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 parsing [default->group_dial_sales] continue=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 parsing [default->group_dial_support] continue=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 parsing [default->group_dial_billing] continue=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 parsing [default->operator] continue=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 parsing [default->vmain] continue=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 parsing [default->sip_uri] continue=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 parsing [default->nb_conferences] continue=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 parsing [default->wb_conferences] continue=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 parsing [default->uwb_conferences] continue=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 parsing [default->cdquality_conferences] continue=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 parsing [default->freeswitch_public_conf_via_sip] continue=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 parsing [default->mad_boss_intercom] continue=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 parsing [default->mad_boss_intercom] continue=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 parsing [default->mad_boss] continue=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 parsing [default->ivr_demo] continue=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 parsing [default->dynamic_conference] continue=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 parsing [default->rtp_multicast_page] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->valet_park] continue=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 parsing [default->valet_park] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->park] continue=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 parsing [default->unpark] continue=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 parsing [default->wait] continue=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 parsing [default->fax_receive] continue=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 parsing [default->fax_transmit] continue=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 parsing [default->ringback_180] continue=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 parsing [default->ringback_183_uk_ring] continue=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 parsing [default->ringback_183_music_ring] continue=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 parsing [default->ringback_post_answer_uk_ring] continue=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 parsing [default->ringback_post_answer_music] continue=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 parsing [default->ClueCon] continue=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 parsing [default->show_info] continue=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 parsing [default->video_record] continue=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 parsing [default->video_playback] continue=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 parsing [default->delay_echo] continue=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 parsing [default->echo] continue=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 parsing [default->milliwatt] continue=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 parsing [default->tone_stream] continue=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 parsing [default->zrtp_enrollement] continue=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 parsing [default->hold_music] continue=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 parsing [default->laugh break] continue=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 parsing [default->101] continue=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 parsing [default->pizza_demo] continue=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 parsing [default->local.example.com] continue=false
Dialplan: sofia/testing/10701...@125.99.186.124 Regex (FAIL) [local.example.com] ${toll_allow}() =~ /local/ break=on-false
Dialplan: sofia/testing/10701...@125.99.186.124 parsing [default->domestic.example.com] continue=false
Dialplan: sofia/testing/10701...@125.99.186.124 Regex (FAIL) [domestic.example.com] ${toll_allow}() =~ /domestic/ break=on-false
Dialplan: sofia/testing/10701...@125.99.186.124 parsing [default->international.example.com] continue=false
Dialplan: sofia/testing/10701...@125.99.186.124 Regex (FAIL) [international.example.com] ${toll_allow}() =~ /international/ break=on-false
Dialplan: sofia/testing/10701...@125.99.186.124 parsing [default->Talking Clock Time] continue=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 parsing [default->Talking Clock Date] continue=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 parsing [default->Talking Clock Date and Time] continue=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 parsing [default->astpp_callingcards] continue=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 parsing [default->enum] continue=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
Dialplan: sofia/testing/10701...@125.99.186.124 Action transfer(1163250936 enum) 
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] mod_sofia.c:178 sofia/testing/10701...@125.99.186.124 SOFIA EXECUTE
2013-11-16 15:29:03.875319 [DEBUG] switch_core_state_machine.c:256 sofia/testing/10701...@125.99.186.124 Standard EXECUTE
EXECUTE sofia/testing/10701...@125.99.186.124 transfer(1163250936 enum)
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] mod_sofia.c:123 sofia/testing/10701...@125.99.186.124 SOFIA 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] mod_sofia.c:178 sofia/testing/10701...@125.99.186.124 SOFIA 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_channel.c:3171 Send signal sofia/testing/10701...@125.99.186.124 [KILL]
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] mod_sofia.c:323 sofia/testing/10701...@125.99.186.124 SOFIA 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


Please help me sir.

Thanks and Regards,
Sandeep Rawat.



Reply all
Reply to author
Forward
0 new messages