[Freeswitch-users] Possible registration/presence bug - Sometimes get "Originate Failed. Cause: USER_NOT_REGISTERED" when extension registration is present

Mon Apr 2 10:26:00 MSD 2012

And use Jira to report bugs please.

/b

Sent from my iPhone

On Apr 2, 2012, at 1:20 AM, Anton Kvashenkin <anton.jugatsu at gmail.com>
wrote:

I think that the best way to check endpoints registrations is sofia status
profile internal reg. I have sometimes accuracy problems with show
registrations.

2 апреля 2012 г. 8:37 пользователь Phil Quesinberry <
philq at qsystemsengineering.com> написал:

> **
>
> Hi,
>
> I’ve noticed what appears to be a bug which I wanted to bring to your
> attention.  Sometimes when FS is started, extensions can’t be called even
> after they’ve registered.  This doesn’t happen all the time… and
> restarting FS always solves the problem.  Just to be clear - this problem only
> sometimes occurs when FS has been started/restarted, like after an
> update.  Once FS has “started successfully” and sees presence for
> registered extensions, it continues to work fine.
>
> It’s all or nothing – no extensions can be dialed when this issue is
> present, whether they’re local or NATted.  If one extension can be
> reached, you can reach them all (if they’re registered, of course).
>
> See debug info below on an attempt to dial a local (no NAT) extension –this was one of the recent beta git pulls and the problem has been present
> through several updates.  Let me know if you need any additional info.
>
> freeswitch at internal> show registrations
>
>
> reg_user,realm,token,url,expires,network_ip,network_port,network_proto,hostname
>
> 102,192.168.1.6,a7289b6393a8023e,sofia/internal/sip:102 at 192.168.1.4:5060
> ;transport=udp,1333338853,192.168.1.4,5060,udp,server.home
>
> 257,192.168.1.6,aab02ec49af03567,sofia/internal/sip:257 at 74.93.222.182:5060
> ;transport=udp,1333338709,74.93.xx.xx,5060,udp,server.home
>
> 2001,192.168.1.6,bae1af9975ebfa50,sofia/internal/sip:2001 at 192.168.1.4:5060
> ;transport=udp,1333338743,192.168.1.4,5060,udp,server.home
>
> 227,192.168.1.6,42db21f84f52e3ef,sofia/internal/sip:227 at 74.93.222.182:1066
> ;transport=udp,1333338747,74.93.xx.xx,1066,udp,server.home
>
> 226,192.168.1.6,25619841fc53c75e,sofia/internal/sip:226 at 74.93.222.182:1068
> ;transport=udp,1333338751,74.93.xx.xx,1068,udp,server.home
>
> 225,192.168.1.6,308cce2ca1734cdc,sofia/internal/sip:225 at 74.93.222.182:1067
> ;transport=udp,1333338751,74.93.xx.xx,1067,udp,server.home
>
>
> 3900,192.168.1.6,54667b49ca74cabe,sofia/internal/sip:3900 at 173.66.100.94:54629
> ;transport=udp;fs_nat=yes,1333338762,173.66.xx.xx,54629,udp,server.home
>
>
> 3901,192.168.1.6,6dc54f7994ffb61a,sofia/internal/sip:3901 at 173.66.100.94:6480
> ;transport=udp;fs_nat=yes,1333338779,173.66.xx.xx,6480,udp,server.home
>
> 519,192.168.1.6,45249b30-85ddd13a at 10.0.0.159,sofia/internal/
> sip:519 at 74.93.222.182:1062,1333338779,74.93.xx.xx,1062,udp,server.home
>
> 9 total.
>
> 2012-04-01 23:50:41.449612 [DEBUG] sofia.c:7576 IP 192.168.1.4 Rejected by
> acl "domains". Falling back to Digest auth.
>
> 2012-04-01 23:50:41.449612 [WARNING] sofia_reg.c:1422 SIP auth challenge
> (INVITE) on sofia profile 'internal' for [2001 at 192.168.1.6] from ip
> 192.168.1.4
>
> 2012-04-01 23:50:41.549553 [DEBUG] sofia.c:7576 IP 192.168.1.4 Rejected by
> acl "domains". Falling back to Digest auth.
>
> 2012-04-01 23:50:41.549553 [NOTICE] switch_channel.c:926 New Channel
> sofia/internal/102 at 192.168.1.6:5060 [9a9a3bde-116c-4100-b269-a6be7455c6c1]
>
> 2012-04-01 23:50:41.549553 [DEBUG] sofia.c:5541 Channel sofia/internal/
> 102 at 192.168.1.6:5060 entering state [received][100]
>
> 2012-04-01 23:50:41.549553 [DEBUG] sofia.c:5552 Remote SDP:
>
> v=0
>
> o=MxSIP 0 1 IN IP4 192.168.1.4
>
> s=SIP Call
>
> c=IN IP4 192.168.1.4
>
> t=0 0
>
> m=audio 16384 RTP/AVP 0 110 8 9
>
> a=rtpmap:0 PCMU/8000
>
> a=rtpmap:110 PCMU/16000
>
> a=rtpmap:8 PCMA/8000
>
> a=rtpmap:9 G722/8000
>
> a=silenceSupp:off - - - -
>
> a=ptime:20
>
> 2012-04-01 23:50:41.549553 [DEBUG] sofia.c:5746 (sofia/internal/
> 102 at 192.168.1.6:5060) State Change CS_NEW -> CS_INIT
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_INIT
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/102 at 192.168.1.6:5060) State INIT
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_sofia.c:85 sofia/internal/
> 102 at 192.168.1.6:5060 SOFIA INIT
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_sofia.c:125 (sofia/internal/
> 102 at 192.168.1.6:5060) State Change CS_INIT -> CS_ROUTING
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/102 at 192.168.1.6:5060) State INIT going to sleep
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_ROUTING
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_channel.c:1886 (sofia/internal/
> 102 at 192.168.1.6:5060) Callstate Change DOWN -> RINGING
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/102 at 192.168.1.6:5060) State ROUTING
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_sofia.c:148 sofia/internal/
> 102 at 192.168.1.6:5060 SOFIA ROUTING
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:104
> sofia/internal/102 at 192.168.1.6:5060 Standard ROUTING
>
> 2012-04-01 23:50:41.549553 [INFO] mod_dialplan_xml.c:485 Processing Phil
> <102>->2001 in context default
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->unloop]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [unloop]
> ${unroll_loops}(true) =~ /^true$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [unloop]
> ${sip_looped_call}() =~ /^true$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->call_direction] continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [call_direction] ${call_direction}() =~ /^(inbound|outbound|local)$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 ANTI-Action
> set(call_direction=local)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->tod_example] continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Date/TimeMatch (FAIL)
> [tod_example] break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->holiday_example] continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Date/TimeMatch (FAIL)
> [holiday_example] break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->group-intercept] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [group-intercept] destination_number(2001) =~ /^\*8$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->redial]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [redial]
> destination_number(2001) =~ /^(redial|\*870)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->global]
> continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [global]
> ${call_debug}(false) =~ /^true$/ break=never
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [global]
> ${sip_has_crypto}() =~
> /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Absolute Condition [global]
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-last_dial/global/${uuid})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->snom-demo-2] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [snom-demo-2]
> destination_number(2001) =~ /^\*9001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->snom-demo-1] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [snom-demo-1]
> destination_number(2001) =~ /^\*9000$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->call_privacy] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [call_privacy]
> destination_number(2001) =~ /^\*67(\d+)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->call_return] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [call_return]
> destination_number(2001) =~ /^\*69$|^869$|^lcr$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->del-group] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [del-group]
> destination_number(2001) =~ /^\*\*80(\d{2})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->add-group] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [add-group]
> destination_number(2001) =~ /^\*\*81(\d{2})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->call-group-simo] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [call-group-simo] destination_number(2001) =~ /^\*\*82(\d{2})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->call-group-order] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [call-group-order] destination_number(2001) =~ /^\*83(\d{2})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->intercept-ext] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [intercept-ext] destination_number(2001) =~ /^\*\*(\d+)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Local_Extension_Skinny] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [Local_Extension_Skinny] destination_number(2001) =~ /^(11[01][0-9])$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->send_to_voicemail] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [send_to_voicemail] destination_number(2001) =~ /^\*99(\d{2,7})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->enum.9.10d] continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [enum.9.10d]
> destination_number(2001) =~ /^9(\d{10})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->enum.9.11d] continue=true
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [enum.9.11d]
> destination_number(2001) =~ /^9(\d{11})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->SipBroker.SIPDial] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [SipBroker.SIPDial] destination_number(2001) =~ /^([*]\d{5,})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->SipBroker.Toll_Free] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [SipBroker.Toll_Free] destination_number(2001) =~
> /^91?(8(00|66|77|88)[2-9]\d{6})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->TollFreeGateway] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [TollFreeGateway] destination_number(2001) =~
> /^91?(8(00|55|66|77|88)[2-9]\d{6})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->FlowRoute.91d10or11] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [FlowRoute.91d10or11] destination_number(2001) =~ /^91?(\d{10})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->HL7519.9.10d] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [HL7519.9.10d]
> caller_id_number(102) =~ /519/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->HL7519.9.11d] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [HL7519.9.11d]
> caller_id_number(102) =~ /519/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->HL7612.9.10d] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [HL7612.9.10d]
> ${toll_allow}(local,domestic) =~ /local/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [HL7612.9.10d]
> destination_number(2001) =~ /^9(\d{10})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->HL7612.9.11d] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [HL7612.9.11d]
> ${toll_allow}(local,domestic) =~ /local/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [HL7612.9.11d]
> destination_number(2001) =~ /^9(\d{11})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->enum.7d7]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [enum.7d7]
> destination_number(2001) =~ /^7(\d{7})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->xmpp.8d10] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [xmpp.8d10]
> destination_number(2001) =~ /^8(\d{10})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->xmpp.8d11] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [xmpp.8d11]
> destination_number(2001) =~ /^8(\d{11})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->Talking
> Clock Time] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Talking Clock
> Time] destination_number(2001) =~ /^9170$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->Talking
> Clock Date] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Talking Clock
> Date] destination_number(2001) =~ /^9171$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->Talking
> Clock Date and Time] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Talking Clock
> Date and Time] destination_number(2001) =~ /^9172$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Recordings] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Recordings]
> destination_number(2001) =~ /^\*(732)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Company_Directory] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [Company_Directory] destination_number(2001) =~ /^5001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Hersch_Lauren_Main] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [Hersch_Lauren_Main] destination_number(2001) =~ /^5000$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Holiday_Announcement] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [Holiday_Announcement] destination_number(2001) =~ /^5010$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->Ring all
> admin phones.park] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Ring all
> admin phones.park] destination_number(2001) =~ /^\*1001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->Ring all
> admin phones] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [Ring all
> admin phones] destination_number(2001) =~ /^1001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->group_dial_sales] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [group_dial_sales] destination_number(2001) =~ /^\*2000$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->group_dial_support] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [group_dial_support] destination_number(2001) =~ /^\*2001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->group_dial_billing] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [group_dial_billing] destination_number(2001) =~ /^\*2002$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->operator]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [operator]
> destination_number(2001) =~ /^operator$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->vmain]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [vmain]
> destination_number(2001) =~ /^vmain$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->vmain1]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [vmain1]
> destination_number(2001) =~ /^vmain1$|^\*97$|^\*4000$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->vmain2]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [vmain2]
> destination_number(2001) =~ /^vmain2$|^\*98$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->sip_uri]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [sip_uri]
> destination_number(2001) =~ /^sip:(.*)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->nb_conferences] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [nb_conferences] destination_number(2001) =~ /^\*(30\d{2})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->wb_conferences] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [wb_conferences] destination_number(2001) =~ /^\*(31\d{2})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->uwb_conferences] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [uwb_conferences] destination_number(2001) =~ /^\*(32\d{2})$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->cdquality_conferences] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [cdquality_conferences] destination_number(2001) =~ /^\*(33\d{2})$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->freeswitch_public_conf_via_sip] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [freeswitch_public_conf_via_sip] destination_number(2001) =~
> /^\*9(888|8888|1616|3232)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->ivr_demo]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [ivr_demo]
> destination_number(2001) =~ /^\*5000$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->dynamic_conference] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [dynamic_conference] destination_number(2001) =~ /^\*5001$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->rtp_multicast_page] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [rtp_multicast_page] destination_number(2001) =~ /^pagegroup$|^\*7243$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->parking_slots] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [parking_slots] destination_number(2001) =~ /^(\*59[0-9][0-9])$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->valet_park_in] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [valet_park_in] destination_number(2001) =~ /^\*(6000)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->valet_park_out] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [valet_park_out] destination_number(2001) =~ /^\*(60\d\d)$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->park]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [park]
> source(mod_sofia) =~ /mod_sofia/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [park]
> destination_number(2001) =~ /park\+(\d+)/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->unpark]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [unpark]
> source(mod_sofia) =~ /mod_sofia/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [unpark]
> destination_number(2001) =~ /^parking$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->park]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [park]
> source(mod_sofia) =~ /mod_sofia/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [park]
> destination_number(2001) =~ /callpark/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->unpark]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS) [unpark]
> source(mod_sofia) =~ /mod_sofia/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [unpark]
> destination_number(2001) =~ /pickup/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->wait]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [wait]
> destination_number(2001) =~ /^wait$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->fax_receive] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [fax_receive]
> destination_number(2001) =~ /^\*9178$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->fax_transmit] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [fax_transmit]
> destination_number(2001) =~ /^\*9179$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->ringback_180] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [ringback_180]
> destination_number(2001) =~ /^\*9180$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->ringback_183_uk_ring] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [ringback_183_uk_ring] destination_number(2001) =~ /^\*9181$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->ringback_183_music_ring] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [ringback_183_music_ring] destination_number(2001) =~ /^\*9182$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->ringback_post_answer_uk_ring] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [ringback_post_answer_uk_ring] destination_number(2001) =~ /^\*9183$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->ringback_post_answer_music] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [ringback_post_answer_music] destination_number(2001) =~ /^\*9184$/
> break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->ClueCon]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [ClueCon]
> destination_number(2001) =~ /^\*9191$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->show_info] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [show_info]
> destination_number(2001) =~ /^\*9192$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->video_record] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [video_record]
> destination_number(2001) =~ /^\*9193$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->video_playback] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [video_playback] destination_number(2001) =~ /^\*9194$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->delay_echo] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [delay_echo]
> destination_number(2001) =~ /^\*9195$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing [default->echo]
> continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [echo]
> destination_number(2001) =~ /^\*9196$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->milliwatt] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [milliwatt]
> destination_number(2001) =~ /^\*9197$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->tone_stream] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [tone_stream]
> destination_number(2001) =~ /^\*9198$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->zrtp_enrollement] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL)
> [zrtp_enrollement] destination_number(2001) =~ /^\*9787$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->hold_music] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (FAIL) [hold_music]
> destination_number(2001) =~ /^\*9664$/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 parsing
> [default->Local_Extension] continue=false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Regex (PASS)
> [Local_Extension] destination_number(2001) =~ /(^\d{2,7}$)/ break=on-false
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(dialed_extension=2001)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> export(dialed_extension=2001)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action limit(db
> ${domain_name} 2001 ${limit_max} ${limit_destination})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action bind_meta_app(1 b s
> execute_extension::dx XML features)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action bind_meta_app(2 b s
> record_session::/usr/local/freeswitch/recordings/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.wav)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action bind_meta_app(3 b s
> execute_extension::cf XML features)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action bind_meta_app(4 b s
> execute_extension::att_xfer XML features)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action set(ringback=%(2000,
> 4000, 440.0, 480.0))
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(transfer_ringback=%(2000, 4000, 440.0, 480.0))
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action set(call_timeout=24)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(hangup_after_bridge=true)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(continue_on_fail=true)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name}
> var callgroup)})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action
> bridge(user/${user_data(${destination_number}@${domain_name} attr
> id)}@${domain_name})
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action answer()
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action sleep(1000)
>
> Dialplan: sofia/internal/102 at 192.168.1.6:5060 Action voicemail(default
> ${domain_name} ${dialed_extension})
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:154
> (sofia/internal/102 at 192.168.1.6:5060) State Change CS_ROUTING ->
> CS_EXECUTE
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/102 at 192.168.1.6:5060) State ROUTING going to sleep
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_EXECUTE
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/102 at 192.168.1.6:5060) State EXECUTE
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_sofia.c:241 sofia/internal/
> 102 at 192.168.1.6:5060 SOFIA EXECUTE
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_core_state_machine.c:192
> sofia/internal/102 at 192.168.1.6:5060 Standard EXECUTE
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(call_direction=local)
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [call_direction]=[local]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-spymap/102/9a9a3bde-116c-4100-b269-a6be7455c6c1)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-last_dial/102/2001)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-last_dial/global/9a9a3bde-116c-4100-b269-a6be7455c6c1)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(RFC2822_DATE=Sun, 01 Apr
> 2012 23:50:41 -0400)
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [RFC2822_DATE]=[Sun, 01 Apr 2012 23:50:41 -0400]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(dialed_extension=2001)
>
> 2012-04-01 23:50:41.549553 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [dialed_extension]=[2001]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 export(dialed_extension=2001)
>
> 2012-04-01 23:50:41.549553 [DEBUG] switch_channel.c:1093 EXPORT
> (export_vars) [dialed_extension]=[2001]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 limit(db 192.168.1.6 2001 5 )
>
> 2012-04-01 23:50:41.549553 [INFO] switch_limit.c:126 incr called:
> 192.168.1.6_2001 max:5, interval:0
>
> 2012-04-01 23:50:41.569549 [INFO] mod_db.c:197 Usage for 192.168.1.6_2001
> is now 1/5
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 bind_meta_app(1 b s
> execute_extension::dx XML features)
>
> 2012-04-01 23:50:41.569549 [INFO] switch_ivr_async.c:3204 Bound B-Leg: *1
> execute_extension::dx XML features
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 bind_meta_app(2 b s
> record_session::/usr/local/freeswitch/recordings/archive/2012/Apr/01/9a9a3bde-116c-4100-b269-a6be7455c6c1.wav)
>
> 2012-04-01 23:50:41.569549 [INFO] switch_ivr_async.c:3204 Bound B-Leg: *2
> record_session::/usr/local/freeswitch/recordings/archive/2012/Apr/01/9a9a3bde-116c-4100-b269-a6be7455c6c1.wav
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 bind_meta_app(3 b s
> execute_extension::cf XML features)
>
> 2012-04-01 23:50:41.569549 [INFO] switch_ivr_async.c:3204 Bound B-Leg: *3
> execute_extension::cf XML features
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 bind_meta_app(4 b s
> execute_extension::att_xfer XML features)
>
> 2012-04-01 23:50:41.569549 [INFO] switch_ivr_async.c:3204 Bound B-Leg: *4
> execute_extension::att_xfer XML features
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(ringback=%(2000, 4000,
> 440.0, 480.0))
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [ringback]=[%(2000, 4000, 440.0, 480.0)]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(transfer_ringback=%(2000,
> 4000, 440.0, 480.0))
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [transfer_ringback]=[%(2000, 4000, 440.0, 480.0)]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(call_timeout=24)
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [call_timeout]=[24]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(hangup_after_bridge=true)
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [hangup_after_bridge]=[true]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(continue_on_fail=true)
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [continue_on_fail]=[true]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-call_return/2001/102)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-last_dial_ext/2001/9a9a3bde-116c-4100-b269-a6be7455c6c1)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 set(called_party_callgroup=)
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 102 at 192.168.1.6:5060 SET [called_party_callgroup]=[UNDEF]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060hash(insert/192.168.1.6-last_dial//9a9a3bde-116c-4100-b269-a6be7455c6c1)
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 bridge(user/2001 at 192.168.1.6)
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_channel.c:1047 sofia/internal/
> 102 at 192.168.1.6:5060 EXPORTING[export_vars] [dialed_extension]=[2001] to
> event
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_ivr_originate.c:1884 Parsing
> global variables
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_channel.c:1047 sofia/internal/
> 102 at 192.168.1.6:5060 EXPORTING[export_vars] [dialed_extension]=[2001] to
> event
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_ivr_originate.c:1884 Parsing
> global variables
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_event.c:1522 Parsing variable
> [sip_invite_domain]=[192.168.1.6]
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_event.c:1522 Parsing variable
> [presence_id]=[2001 at 192.168.1.6]
>
> 2012-04-01 23:50:41.569549 [NOTICE] switch_ivr_originate.c:2459 Cannot
> create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_ivr_originate.c:3364 Originate
> Resulted in Error Cause: 606 [USER_NOT_REGISTERED]
>
> 2012-04-01 23:50:41.569549 [NOTICE] switch_ivr_originate.c:2459 Cannot
> create outgoing channel of type [user] cause: [USER_NOT_REGISTERED]
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_ivr_originate.c:3364 Originate
> Resulted in Error Cause: 606 [USER_NOT_REGISTERED]
>
> 2012-04-01 23:50:41.569549 [INFO] mod_dptools.c:2922 Originate Failed.
> Cause: USER_NOT_REGISTERED
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 answer()
>
> 2012-04-01 23:50:41.569549 [DEBUG] sofia_glue.c:4876 Audio Codec Compare
> [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
>
> 2012-04-01 23:50:41.569549 [DEBUG] sofia_glue.c:2996 Set Codec
> sofia/internal/102 at 192.168.1.6:5060 PCMU/8000 20 ms 160 samples 64000 bits
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_core_codec.c:111 sofia/internal/
> 102 at 192.168.1.6:5060 Original read codec set to PCMU:0
>
> 2012-04-01 23:50:41.569549 [DEBUG] sofia_glue.c:5008 No 2833 in SDP.
> Disable 2833 dtmf and switch to INFO
>
> 2012-04-01 23:50:41.569549 [DEBUG] sofia_glue.c:3248 AUDIO RTP
> [sofia/internal/102 at 192.168.1.6:5060] 192.168.1.6 port 23164 ->
> 192.168.1.4 port 16384 codec: 0 ms: 20
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_rtp.c:1661 Starting timer [soft]
> 160 bytes per 20ms
>
> 2012-04-01 23:50:41.569549 [NOTICE] sofia_glue.c:4022 Pre-Answer
> sofia/internal/102 at 192.168.1.6:5060!
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_channel.c:2932 (sofia/internal/
> 102 at 192.168.1.6:5060) Callstate Change RINGING -> EARLY
>
> 2012-04-01 23:50:41.569549 [DEBUG] mod_sofia.c:750 Local SDP
> sofia/internal/102 at 192.168.1.6:5060:
>
> v=0
>
> o=FreeSWITCH 1333315477 1333315478 IN IP4 192.168.1.6
>
> s=FreeSWITCH
>
> c=IN IP4 192.168.1.6
>
> t=0 0
>
> m=audio 23164 RTP/AVP 0
>
> a=rtpmap:0 PCMU/8000
>
> a=silenceSupp:off - - - -
>
> a=ptime:20
>
> a=sendrecv
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_core_session.c:731 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_channel.c:3190 (sofia/internal/
> 102 at 192.168.1.6:5060) Callstate Change EARLY -> ACTIVE
>
> 2012-04-01 23:50:41.569549 [NOTICE] mod_dptools.c:1135 Channel
> [sofia/internal/102 at 192.168.1.6:5060] has been answered
>
> 2012-04-01 23:50:41.569549 [DEBUG] switch_core_session.c:877 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.569549 [DEBUG] sofia.c:5541 Channel sofia/internal/
> 102 at 192.168.1.6:5060 entering state [completed][200]
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 sleep(1000)
>
> 2012-04-01 23:50:41.649589 [DEBUG] switch_core_session.c:877 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.649589 [DEBUG] switch_core_session.c:877 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.649589 [DEBUG] switch_core_session.c:877 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:41.669832 [DEBUG] sofia.c:5541 Channel sofia/internal/
> 102 at 192.168.1.6:5060 entering state [ready][200]
>
> 2012-04-01 23:50:41.849781 [DEBUG] switch_rtp.c:3224 Correct ip/port
> confirmed.
>
> EXECUTE sofia/internal/102 at 192.168.1.6:5060 voicemail(default 192.168.1.6
> 2001)
>
> 2012-04-01 23:50:42.689527 [DEBUG] switch_ivr_play_say.c:1306 Codec
> Activated L16 at 8000hz 1 channels 20ms
>
> 2012-04-01 23:50:46.249491 [DEBUG] switch_core_session.c:877 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_channel.c:2848 (sofia/internal/
> 102 at 192.168.1.6:5060) Callstate Change ACTIVE -> HANGUP
>
> 2012-04-01 23:50:46.269490 [NOTICE] sofia.c:628 Hangup sofia/internal/
> 102 at 192.168.1.6:5060 [CS_EXECUTE] [NORMAL_CLEARING]
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_channel.c:2871 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [KILL]
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_ivr_play_say.c:1678 done playing
> file /usr/local/freeswitch/storage/voicemail/default/
> 192.168.1.6/2001/greeting_1.wav
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_session.c:2287
> sofia/internal/102 at 192.168.1.6:5060 skip receive message
> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/102 at 192.168.1.6:5060) State EXECUTE going to sleep
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_HANGUP
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/102 at 192.168.1.6:5060) State HANGUP
>
> 2012-04-01 23:50:46.269490 [DEBUG] mod_sofia.c:469 Channel sofia/internal/
> 102 at 192.168.1.6:5060 hanging up, cause: NORMAL_CLEARING
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:47
> sofia/internal/102 at 192.168.1.6:5060 Standard HANGUP, cause:
> NORMAL_CLEARING
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/102 at 192.168.1.6:5060) State HANGUP going to sleep
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:393
> (sofia/internal/102 at 192.168.1.6:5060) State Change CS_HANGUP ->
> CS_REPORTING
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_REPORTING
>
> 2012-04-01 23:50:46.269490 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/102 at 192.168.1.6:5060) State REPORTING
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:79
> sofia/internal/102 at 192.168.1.6:5060 Standard REPORTING, cause:
> NORMAL_CLEARING
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/102 at 192.168.1.6:5060) State REPORTING going to sleep
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:387
> (sofia/internal/102 at 192.168.1.6:5060) State Change CS_REPORTING ->
> CS_DESTROY
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_session.c:1182 Send signal
> sofia/internal/102 at 192.168.1.6:5060 [BREAK]
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_session.c:1382 Session 13
> (sofia/internal/102 at 192.168.1.6:5060) Locked, Waiting on external entities
>
> 2012-04-01 23:50:46.289510 [NOTICE] switch_core_session.c:1400 Session 13
> (sofia/internal/102 at 192.168.1.6:5060) Ended
>
> 2012-04-01 23:50:46.289510 [NOTICE] switch_core_session.c:1402 Close
> Channel sofia/internal/102 at 192.168.1.6:5060 [CS_DESTROY]
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:491
> (sofia/internal/102 at 192.168.1.6:5060) Callstate Change HANGUP -> DOWN
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:494
> (sofia/internal/102 at 192.168.1.6:5060) Running State Change CS_DESTROY
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/102 at 192.168.1.6:5060) State DESTROY
>
> 2012-04-01 23:50:46.289510 [DEBUG] mod_sofia.c:374 sofia/internal/
> 102 at 192.168.1.6:5060 SOFIA DESTROY
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:86
> sofia/internal/102 at 192.168.1.6:5060 Standard DESTROY
>
> 2012-04-01 23:50:46.289510 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/102 at 192.168.1.6:5060) State DESTROY going to sleep
>
> freeswitch at internal> /bye
>
> [root at server bin]#
>
> Regards,
>
> *******Phil Quesinberry*
>
> Q Systems Engineering, Inc.
>
> Electronic Controls and Embedded Systems Development
>
> (410) 969-8002
>
> *****http://www.qsystemsengineering.com*<http://www.qsystemsengineering.com>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> 
> 
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org
http://www.freeswitchsolutions.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120402/1610b616/attachment-0001.html 