[Freeswitch-users] Incoming DID

Mon Aug 22 12:46:03 MSD 2011

Hello Freeswitch-users,

I have problem with incoming DID routing.
Internal  phones  normally  registered  and can make outgoing call and
call to each other.
But incoming DID call routed to voice mail or "goodbye" prompt.
Calls to other services (IVR, conference) are routed normally.
I'm connected to Nortel CS1000 via sip trunk.
Call log below:

2011-08-22 04:44:54.970599 [WARNING] sofia_reg.c:1241 SIP auth challenge (REGISTER) on sofia profile 'internal' for [8100 at 10.160.0.3] from ip 10.160.1.212
2011-08-22 04:45:00.718687 [NOTICE] switch_channel.c:816 New Channel sofia/external/7777 [39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd]
2011-08-22 04:45:00.719679 [DEBUG] sofia.c:4761 Channel sofia/external/7777 entering state [received][100]
2011-08-22 04:45:00.719679 [DEBUG] sofia.c:4772 Remote SDP:
v=0

o=- 663 1 IN IP4 10.1.1.22

s=-

t=0 0

m=audio 5200 RTP/AVP 8 0 18 101 111

c=IN IP4 10.160.0.205

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=rtpmap:111 X-nt-inforeq/8000

a=ptime:20

2011-08-22 04:45:00.719679 [DEBUG] sofia_glue.c:4656 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2011-08-22 04:45:00.719679 [DEBUG] sofia_glue.c:4656 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2011-08-22 04:45:00.719679 [DEBUG] sofia_glue.c:4656 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2011-08-22 04:45:00.719679 [DEBUG] sofia_glue.c:2788 Set Codec sofia/external/7777 PCMA/8000 20 ms 160 samples 64000 bits
2011-08-22 04:45:00.719679 [DEBUG] sofia_glue.c:4770 Set 2833 dtmf send/recv payload to 101
2011-08-22 04:45:00.719679 [DEBUG] sofia.c:4943 (sofia/external/7777) State Change CS_NEW -> CS_INIT
2011-08-22 04:45:00.719679 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_INIT
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:361 (sofia/external/7777) State INIT
2011-08-22 04:45:00.719679 [DEBUG] mod_sofia.c:84 sofia/external/7777 SOFIA INIT
2011-08-22 04:45:00.719679 [DEBUG] mod_sofia.c:124 (sofia/external/7777) State Change CS_INIT -> CS_ROUTING
2011-08-22 04:45:00.719679 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:361 (sofia/external/7777) State INIT going to sleep
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_ROUTING
2011-08-22 04:45:00.719679 [DEBUG] switch_channel.c:1667 (sofia/external/7777) Callstate Change DOWN -> RINGING
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:364 (sofia/external/7777) State ROUTING
2011-08-22 04:45:00.719679 [DEBUG] mod_sofia.c:147 sofia/external/7777 SOFIA ROUTING
2011-08-22 04:45:00.719679 [DEBUG] switch_core_state_machine.c:77 sofia/external/7777 Standard ROUTING
2011-08-22 04:45:00.719679 [INFO] mod_dialplan_xml.c:331 Processing Vasya <7777;phone-context=cdp.udp>->8100;phone-context=cdp.udp in context public
Dialplan: sofia/external/7777 parsing [public->unloop] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/7777 parsing [public->outside_call] continue=true
Dialplan: sofia/external/7777 Absolute Condition [outside_call]
Dialplan: sofia/external/7777 Action set(outside_call=true) 
Dialplan: sofia/external/7777 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/external/7777 parsing [public->call_debug] continue=true
Dialplan: sofia/external/7777 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/7777 parsing [public->public_extensions] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [public_extensions] destination_number(8100;phone-context=cdp.udp) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/7777 parsing [public->8000] continue=true
Dialplan: sofia/external/7777 Regex (PASS) [8000] context(public) =~ /public/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [8000] destination_number(8100;phone-context=cdp.udp) =~ /8000/ break=on-false
Dialplan: sofia/external/7777 parsing [public->8001] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [8001] context(public) =~ /public/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [8001] destination_number(8100;phone-context=cdp.udp) =~ /8001/ break=on-false
Dialplan: sofia/external/7777 parsing [public->8100] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [8100] context(public) =~ /public/ break=on-false
Dialplan: sofia/external/7777 Regex (PASS) [8100] destination_number(8100;phone-context=cdp.udp) =~ /8100/ break=on-false
Dialplan: sofia/external/7777 Action transfer(8100 XML Default) 
2011-08-22 04:45:00.720681 [DEBUG] switch_core_state_machine.c:119 (sofia/external/7777) State Change CS_ROUTING -> CS_EXECUTE
2011-08-22 04:45:00.720681 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.720681 [DEBUG] switch_core_state_machine.c:364 (sofia/external/7777) State ROUTING going to sleep
2011-08-22 04:45:00.720681 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_EXECUTE
2011-08-22 04:45:00.720681 [DEBUG] switch_core_state_machine.c:371 (sofia/external/7777) State EXECUTE
2011-08-22 04:45:00.721680 [DEBUG] mod_sofia.c:240 sofia/external/7777 SOFIA EXECUTE
2011-08-22 04:45:00.721680 [DEBUG] switch_core_state_machine.c:157 sofia/external/7777 Standard EXECUTE
EXECUTE sofia/external/7777 set(outside_call=true)
2011-08-22 04:45:00.721680 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [outside_call]=[true]
EXECUTE sofia/external/7777 set(RFC2822_DATE=Mon, 22 Aug 2011 04:45:00 +0300)
2011-08-22 04:45:00.721680 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [RFC2822_DATE]=[Mon, 22 Aug 2011 04:45:00 +0300]
EXECUTE sofia/external/7777 transfer(8100 XML Default)
2011-08-22 04:45:00.721680 [DEBUG] switch_ivr.c:1597 (sofia/external/7777) State Change CS_EXECUTE -> CS_ROUTING
2011-08-22 04:45:00.721680 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.721680 [DEBUG] switch_core_session.c:707 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.721680 [NOTICE] switch_ivr.c:1603 Transfer sofia/external/7777 to XML[8100 at Default]
2011-08-22 04:45:00.721680 [DEBUG] switch_core_state_machine.c:371 (sofia/external/7777) State EXECUTE going to sleep
2011-08-22 04:45:00.721680 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_ROUTING
2011-08-22 04:45:00.722681 [DEBUG] switch_core_state_machine.c:364 (sofia/external/7777) State ROUTING
2011-08-22 04:45:00.722681 [DEBUG] mod_sofia.c:147 sofia/external/7777 SOFIA ROUTING
2011-08-22 04:45:00.722681 [DEBUG] switch_core_state_machine.c:77 sofia/external/7777 Standard ROUTING
2011-08-22 04:45:00.722681 [INFO] mod_dialplan_xml.c:331 Processing Golubenkoff A. V. <7777;phone-context=cdp.udp>->8100 in context Default
Dialplan: sofia/external/7777 parsing [Default->unloop] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->tod_example] continue=true
Dialplan: sofia/external/7777 Date/Time Match (FAIL) [tod_example] break=on-false
Dialplan: sofia/external/7777 parsing [Default->holiday_example] continue=true
Dialplan: sofia/external/7777 Date/Time Match (FAIL) [holiday_example] break=on-false
Dialplan: sofia/external/7777 parsing [Default->global-intercept] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [global-intercept] destination_number(8100) =~ /^\*886$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->group-intercept] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [group-intercept] destination_number(8100) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->intercept-ext] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [intercept-ext] destination_number(8100) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->redial] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [redial] destination_number(8100) =~ /^(redial|\*870)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->global] continue=true
Dialplan: sofia/external/7777 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/7777 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/external/7777 Absolute Condition [global]
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-last_dial/global/${uuid}) 
Dialplan: sofia/external/7777 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/external/7777 parsing [Default->snom-demo-2] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [snom-demo-2] destination_number(8100) =~ /^\*9001$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->snom-demo-1] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [snom-demo-1] destination_number(8100) =~ /^\*9000$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->eavesdrop] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [eavesdrop] destination_number(8100) =~ /^\*88(\d{2,7})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->eavesdrop] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [eavesdrop] destination_number(8100) =~ /^\*779$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->call_privacy] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [call_privacy] destination_number(8100) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->call_return] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [call_return] destination_number(8100) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->del-group] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [del-group] destination_number(8100) =~ /^\*\*80(\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->add-group] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [add-group] destination_number(8100) =~ /^\*\*81(\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->call-group-simo] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [call-group-simo] destination_number(8100) =~ /^\*\*82(\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->call-group-order] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [call-group-order] destination_number(8100) =~ /^\*83(\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->extension-intercom] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [extension-intercom] destination_number(8100) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Local_Extension_Skinny] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Local_Extension_Skinny] destination_number(8100) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->send_to_voicemail] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [send_to_voicemail] destination_number(8100) =~ /^\*99(\d{2,7})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Conference] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Conference] destination_number(8100) =~ /^8888$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->sc1000.10d] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [sc1000.10d] destination_number(8100) =~ /^(\d{10})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->sc1000.7d] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [sc1000.7d] destination_number(8100) =~ /^(\d{7})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->sc1000.d4] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [sc1000.d4] destination_number(8100) =~ /^(7\d{3})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Conference_Equation] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Conference_Equation] destination_number(8100) =~ /^8800$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->101] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [101] destination_number(8100) =~ /^101$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->pizza_demo] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [pizza_demo] destination_number(8100) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Talking Clock Time] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Talking Clock Time] destination_number(8100) =~ /9170/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Talking Clock Date] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Talking Clock Date] destination_number(8100) =~ /9171/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Talking Clock Date and Time] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Talking Clock Date and Time] destination_number(8100) =~ /9172/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Recordings] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [Recordings] destination_number(8100) =~ /^\*732$|^\*732673$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->group_dial_sales] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [group_dial_sales] destination_number(8100) =~ /^\*2000$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->group_dial_support] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [group_dial_support] destination_number(8100) =~ /^\*2001$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->group_dial_billing] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [group_dial_billing] destination_number(8100) =~ /^\*2002$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->operator] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [operator] destination_number(8100) =~ /^operator$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->vmain] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [vmain] destination_number(8100) =~ /^vmain$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->vmain1] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [vmain1] destination_number(8100) =~ /^vmain1$|^\*97$|^\*4000$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->vmain2] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [vmain2] destination_number(8100) =~ /^vmain2$|^\*98$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->sip_uri] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [sip_uri] destination_number(8100) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->nb_conferences] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [nb_conferences] destination_number(8100) =~ /^\*(30\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->wb_conferences] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [wb_conferences] destination_number(8100) =~ /^\*(31\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->uwb_conferences] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [uwb_conferences] destination_number(8100) =~ /^\*(32\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->cdquality_conferences] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [cdquality_conferences] destination_number(8100) =~ /^\*(33\d{2})$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(8100) =~ /^\*9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->mad_boss_intercom] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [mad_boss_intercom] destination_number(8100) =~ /^\*0911$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->mad_boss_intercom] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [mad_boss_intercom] destination_number(8100) =~ /^\*0912$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->mad_boss] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [mad_boss] destination_number(8100) =~ /^\*0913$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ivr_demo] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ivr_demo] destination_number(8100) =~ /^\*5000$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->dynamic_conference] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [dynamic_conference] destination_number(8100) =~ /^\*5001$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->rtp_multicast_page] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [rtp_multicast_page] destination_number(8100) =~ /^pagegroup$|^\*7243$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->park] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [park] destination_number(8100) =~ /^\*5900$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->unpark] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [unpark] destination_number(8100) =~ /^\*5901$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->valet_park_in] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [valet_park_in] destination_number(8100) =~ /^\*(6000)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->valet_park_out] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [valet_park_out] destination_number(8100) =~ /^\*(60\d\d)$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->park] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [park] destination_number(8100) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->unpark] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [unpark] destination_number(8100) =~ /^parking$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->park] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [park] destination_number(8100) =~ /callpark/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->unpark] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/external/7777 Regex (FAIL) [unpark] destination_number(8100) =~ /pickup/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->wait] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [wait] destination_number(8100) =~ /^wait$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->fax_receive] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [fax_receive] destination_number(8100) =~ /^\*9178$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->fax_transmit] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [fax_transmit] destination_number(8100) =~ /^\*9179$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ringback_180] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ringback_180] destination_number(8100) =~ /^\*9180$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ringback_183_uk_ring] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ringback_183_uk_ring] destination_number(8100) =~ /^\*9181$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ringback_183_music_ring] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ringback_183_music_ring] destination_number(8100) =~ /^\*9182$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(8100) =~ /^\*9183$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ringback_post_answer_music] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ringback_post_answer_music] destination_number(8100) =~ /^\*9184$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->ClueCon] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [ClueCon] destination_number(8100) =~ /^\*9191$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->show_info] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [show_info] destination_number(8100) =~ /^\*9192$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->video_record] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [video_record] destination_number(8100) =~ /^\*9193$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->video_playback] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [video_playback] destination_number(8100) =~ /^\*9194$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->delay_echo] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [delay_echo] destination_number(8100) =~ /^\*9195$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->echo] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [echo] destination_number(8100) =~ /^\*9196$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->milliwatt] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [milliwatt] destination_number(8100) =~ /^\*9197$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->tone_stream] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [tone_stream] destination_number(8100) =~ /^\*9198$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->zrtp_enrollement] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [zrtp_enrollement] destination_number(8100) =~ /^\*9787$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->hold_music] continue=false
Dialplan: sofia/external/7777 Regex (FAIL) [hold_music] destination_number(8100) =~ /^\*9664$/ break=on-false
Dialplan: sofia/external/7777 parsing [Default->Local_Extension] continue=false
Dialplan: sofia/external/7777 Regex (PASS) [Local_Extension] destination_number(8100) =~ /(^\d{2,7}$)/ break=on-false
Dialplan: sofia/external/7777 Action set(dialed_extension=8100) 
Dialplan: sofia/external/7777 Action export(dialed_extension=8100) 
Dialplan: sofia/external/7777 Action bind_meta_app(1 b s execute_extension::dx XML features) 
Dialplan: sofia/external/7777 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.wav) 
Dialplan: sofia/external/7777 Action bind_meta_app(3 b s execute_extension::cf XML features) 
Dialplan: sofia/external/7777 Action set(ringback=${us-ring}) 
Dialplan: sofia/external/7777 Action set(transfer_ringback=local_stream://moh) 
Dialplan: sofia/external/7777 Action set(call_timeout=30) 
Dialplan: sofia/external/7777 Action set(hangup_after_bridge=true) 
Dialplan: sofia/external/7777 Action set(continue_on_fail=true) 
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 
Dialplan: sofia/external/7777 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) 
Dialplan: sofia/external/7777 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) 
Dialplan: sofia/external/7777 Action bridge(user/${dialed_extension}@${domain_name}) 
Dialplan: sofia/external/7777 Action answer() 
Dialplan: sofia/external/7777 Action sleep(1000) 
Dialplan: sofia/external/7777 Action voicemail(default ${domain_name} ${dialed_extension}) 
2011-08-22 04:45:00.724680 [DEBUG] switch_core_state_machine.c:119 (sofia/external/7777) State Change CS_ROUTING -> CS_EXECUTE
2011-08-22 04:45:00.724680 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.724680 [DEBUG] switch_core_state_machine.c:364 (sofia/external/7777) State ROUTING going to sleep
2011-08-22 04:45:00.724680 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_EXECUTE
2011-08-22 04:45:00.724680 [DEBUG] switch_core_state_machine.c:371 (sofia/external/7777) State EXECUTE
2011-08-22 04:45:00.724680 [DEBUG] mod_sofia.c:240 sofia/external/7777 SOFIA EXECUTE
2011-08-22 04:45:00.724680 [DEBUG] switch_core_state_machine.c:157 sofia/external/7777 Standard EXECUTE
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-spymap/7777;phone-context=cdp.udp/39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd)
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-last_dial/7777;phone-context=cdp.udp/8100)
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-last_dial/global/39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd)
EXECUTE sofia/external/7777 set(RFC2822_DATE=Mon, 22 Aug 2011 04:45:00 +0300)
2011-08-22 04:45:00.725680 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [RFC2822_DATE]=[Mon, 22 Aug 2011 04:45:00 +0300]
EXECUTE sofia/external/7777 set(dialed_extension=8100)
2011-08-22 04:45:00.725680 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [dialed_extension]=[8100]
EXECUTE sofia/external/7777 export(dialed_extension=8100)
2011-08-22 04:45:00.725680 [DEBUG] switch_channel.c:965 EXPORT (export_vars) [dialed_extension]=[8100]
EXECUTE sofia/external/7777 bind_meta_app(1 b s execute_extension::dx XML features)
2011-08-22 04:45:00.726681 [INFO] switch_ivr_async.c:3014 Bound B-Leg: *1 execute_extension::dx XML features
EXECUTE sofia/external/7777 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/archive/2011/Aug/22/39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd.wav)
2011-08-22 04:45:00.726681 [INFO] switch_ivr_async.c:3014 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/archive/2011/Aug/22/39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd.wav
EXECUTE sofia/external/7777 bind_meta_app(3 b s execute_extension::cf XML features)
2011-08-22 04:45:00.726681 [INFO] switch_ivr_async.c:3014 Bound B-Leg: *3 execute_extension::cf XML features
EXECUTE sofia/external/7777 set(ringback=%(2000, 4000, 440.0, 480.0))
2011-08-22 04:45:00.726681 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [ringback]=[%(2000, 4000, 440.0, 480.0)]
EXECUTE sofia/external/7777 set(transfer_ringback=local_stream://moh)
2011-08-22 04:45:00.727682 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [transfer_ringback]=[local_stream://moh]
EXECUTE sofia/external/7777 set(call_timeout=30)
2011-08-22 04:45:00.727682 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [call_timeout]=[30]
EXECUTE sofia/external/7777 set(hangup_after_bridge=true)
2011-08-22 04:45:00.727682 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [hangup_after_bridge]=[true]
EXECUTE sofia/external/7777 set(continue_on_fail=true)
2011-08-22 04:45:00.727682 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [continue_on_fail]=[true]
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-call_return/8100/7777;phone-context=cdp.udp)
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-last_dial_ext/8100/39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd)
EXECUTE sofia/external/7777 set(called_party_callgroup=)
2011-08-22 04:45:00.728678 [DEBUG] mod_dptools.c:1060 sofia/external/7777 SET [called_party_callgroup]=[UNDEF]
EXECUTE sofia/external/7777 hash(insert/10.160.0.3-last_dial//39a3c5ec-834a-4c80-9b2e-b95e1b0f6fbd)
EXECUTE sofia/external/7777 bridge(user/8100 at 10.160.0.3)
2011-08-22 04:45:00.729678 [DEBUG] switch_channel.c:922 sofia/external/7777 EXPORTING[export_vars] [dialed_extension]=[8100] to event
2011-08-22 04:45:00.729678 [DEBUG] switch_ivr_originate.c:1873 Parsing global variables
2011-08-22 04:45:00.729678 [DEBUG] switch_channel.c:922 sofia/external/7777 EXPORTING[export_vars] [dialed_extension]=[8100] to event
2011-08-22 04:45:00.729678 [DEBUG] switch_ivr_originate.c:1873 Parsing global variables
2011-08-22 04:45:00.729678 [DEBUG] switch_event.c:1170 Parsing variable [presence_id]=[8100 at 10.160.0.3]
2011-08-22 04:45:00.730678 [NOTICE] switch_channel.c:816 New Channel sofia/internal/sip:8100 at 10.160.1.212:5060 [cb0e41a2-c78f-492d-bf62-0253dc747736]
2011-08-22 04:45:00.730678 [DEBUG] mod_sofia.c:4305 (sofia/internal/sip:8100 at 10.160.1.212:5060) State Change CS_NEW -> CS_INIT
2011-08-22 04:45:00.730678 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.730678 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_INIT
2011-08-22 04:45:00.730678 [DEBUG] switch_core_state_machine.c:361 (sofia/internal/sip:8100 at 10.160.1.212:5060) State INIT
2011-08-22 04:45:00.730678 [DEBUG] mod_sofia.c:84 sofia/internal/sip:8100 at 10.160.1.212:5060 SOFIA INIT
2011-08-22 04:45:00.731677 [DEBUG] mod_sofia.c:124 (sofia/internal/sip:8100 at 10.160.1.212:5060) State Change CS_INIT -> CS_ROUTING
2011-08-22 04:45:00.731677 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:361 (sofia/internal/sip:8100 at 10.160.1.212:5060) State INIT going to sleep
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_ROUTING
2011-08-22 04:45:00.731677 [DEBUG] switch_channel.c:1667 (sofia/internal/sip:8100 at 10.160.1.212:5060) Callstate Change DOWN -> RINGING
2011-08-22 04:45:00.731677 [DEBUG] sofia.c:4761 Channel sofia/internal/sip:8100 at 10.160.1.212:5060 entering state [calling][0]
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/sip:8100 at 10.160.1.212:5060) State ROUTING
2011-08-22 04:45:00.731677 [DEBUG] mod_sofia.c:147 sofia/internal/sip:8100 at 10.160.1.212:5060 SOFIA ROUTING
2011-08-22 04:45:00.731677 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:8100 at 10.160.1.212:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2011-08-22 04:45:00.731677 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/sip:8100 at 10.160.1.212:5060) State ROUTING going to sleep
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_CONSUME_MEDIA
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:383 (sofia/internal/sip:8100 at 10.160.1.212:5060) State CONSUME_MEDIA
2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:383 (sofia/internal/sip:8100 at 10.160.1.212:5060) State CONSUME_MEDIA going to sleep
2011-08-22 04:45:00.741675 [DEBUG] sofia.c:4761 Channel sofia/internal/sip:8100 at 10.160.1.212:5060 entering state [terminated][415]
2011-08-22 04:45:00.741675 [DEBUG] switch_channel.c:2562 (sofia/internal/sip:8100 at 10.160.1.212:5060) Callstate Change RINGING -> HANGUP
2011-08-22 04:45:00.741675 [NOTICE] sofia.c:5407 Hangup sofia/internal/sip:8100 at 10.160.1.212:5060 [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]
2011-08-22 04:45:00.741675 [DEBUG] switch_channel.c:2578 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [KILL]
2011-08-22 04:45:00.741675 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.741675 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_HANGUP
2011-08-22 04:45:00.741675 [DEBUG] switch_ivr_originate.c:3299 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]
2011-08-22 04:45:00.741675 [ERR] switch_ivr_originate.c:2447 Cannot create outgoing channel of type [user] cause: [SERVICE_NOT_IMPLEMENTED]
2011-08-22 04:45:00.741675 [DEBUG] switch_ivr_originate.c:3299 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]
2011-08-22 04:45:00.741675 [INFO] mod_dptools.c:2647 Originate Failed.  Cause: SERVICE_NOT_IMPLEMENTED
EXECUTE sofia/external/7777 answer()
2011-08-22 04:45:00.742676 [DEBUG] sofia_glue.c:3022 AUDIO RTP [sofia/external/7777] 10.160.0.3 port 23546 -> 10.160.0.205 port 5200 codec: 8 ms: 20
2011-08-22 04:45:00.742676 [DEBUG] switch_rtp.c:1623 Starting timer [soft] 160 bytes per 20ms
2011-08-22 04:45:00.742676 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/sip:8100 at 10.160.1.212:5060) State HANGUP
2011-08-22 04:45:00.742676 [DEBUG] mod_sofia.c:451 sofia/internal/sip:8100 at 10.160.1.212:5060 Overriding SIP cause 501 with 415 from the other leg
2011-08-22 04:45:00.742676 [DEBUG] mod_sofia.c:457 Channel sofia/internal/sip:8100 at 10.160.1.212:5060 hanging up, cause: SERVICE_NOT_IMPLEMENTED
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:8100 at 10.160.1.212:5060 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/sip:8100 at 10.160.1.212:5060) State HANGUP going to sleep
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/sip:8100 at 10.160.1.212:5060) State Change CS_HANGUP -> CS_REPORTING
2011-08-22 04:45:00.743676 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_REPORTING
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:8100 at 10.160.1.212:5060) State REPORTING
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:8100 at 10.160.1.212:5060 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:8100 at 10.160.1.212:5060) State REPORTING going to sleep
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:350 (sofia/internal/sip:8100 at 10.160.1.212:5060) State Change CS_REPORTING -> CS_DESTROY
2011-08-22 04:45:00.743676 [DEBUG] switch_core_session.c:1114 Send signal sofia/internal/sip:8100 at 10.160.1.212:5060 [BREAK]
2011-08-22 04:45:00.743676 [DEBUG] switch_core_session.c:1286 Session 530 (sofia/internal/sip:8100 at 10.160.1.212:5060) Locked, Waiting on external entities
2011-08-22 04:45:00.743676 [NOTICE] switch_core_session.c:1304 Session 530 (sofia/internal/sip:8100 at 10.160.1.212:5060) Ended
2011-08-22 04:45:00.743676 [NOTICE] switch_core_session.c:1306 Close Channel sofia/internal/sip:8100 at 10.160.1.212:5060 [CS_DESTROY]
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/sip:8100 at 10.160.1.212:5060) Callstate Change HANGUP -> DOWN
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:457 (sofia/internal/sip:8100 at 10.160.1.212:5060) Running State Change CS_DESTROY
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:8100 at 10.160.1.212:5060) State DESTROY
2011-08-22 04:45:00.743676 [DEBUG] mod_sofia.c:362 sofia/internal/sip:8100 at 10.160.1.212:5060 SOFIA DESTROY
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:8100 at 10.160.1.212:5060 Standard DESTROY
2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:8100 at 10.160.1.212:5060) State DESTROY going to sleep
2011-08-22 04:45:00.743676 [DEBUG] sofia_glue.c:3284 Set 2833 dtmf send payload to 101
2011-08-22 04:45:00.744676 [DEBUG] sofia_glue.c:3289 Set 2833 dtmf receive payload to 101
2011-08-22 04:45:00.744676 [DEBUG] mod_sofia.c:681 Local SDP sofia/external/7777:
v=0
o=FreeSWITCH 1313953954 1313953955 IN IP4 10.160.0.3
s=FreeSWITCH
c=IN IP4 10.160.0.3
t=0 0
m=audio 23546 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2011-08-22 04:45:00.744676 [DEBUG] switch_core_session.c:707 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:00.744676 [DEBUG] switch_channel.c:2829 (sofia/external/7777) Callstate Change RINGING -> ACTIVE
2011-08-22 04:45:00.744676 [NOTICE] mod_dptools.c:930 Channel [sofia/external/7777] has been answered
2011-08-22 04:45:00.744676 [DEBUG] sofia.c:4761 Channel sofia/external/7777 entering state [completed][200]
EXECUTE sofia/external/7777 sleep(1000)
2011-08-22 04:45:00.746677 [DEBUG] sofia.c:4761 Channel sofia/external/7777 entering state [ready][200]
2011-08-22 04:45:00.945642 [DEBUG] switch_rtp.c:3082 Correct ip/port confirmed.
EXECUTE sofia/external/7777 voicemail(default 10.160.0.3 8100)
2011-08-22 04:45:01.866492 [DEBUG] switch_ivr_play_say.c:67 No language specified - Using [ru]
2011-08-22 04:45:01.877489 [DEBUG] switch_ivr_play_say.c:244 Handle play-file:[voicemail/vm-person.wav] (ru:ru)
2011-08-22 04:45:01.877489 [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1 channels 20ms
2011-08-22 04:45:02.220434 [DEBUG] switch_channel.c:2562 (sofia/external/7777) Callstate Change ACTIVE -> HANGUP
2011-08-22 04:45:02.220434 [NOTICE] sofia.c:538 Hangup sofia/external/7777 [CS_EXECUTE] [NORMAL_CLEARING]
2011-08-22 04:45:02.220434 [DEBUG] switch_channel.c:2578 Send signal sofia/external/7777 [KILL]
2011-08-22 04:45:02.221602 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:02.226433 [DEBUG] switch_ivr_play_say.c:1649 done playing file
2011-08-22 04:45:02.326421 [DEBUG] switch_ivr_play_say.c:244 Handle say:[8100] (ru:ru)
2011-08-22 04:45:02.428408 [DEBUG] switch_core_session.c:2057 sofia/external/7777 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2011-08-22 04:45:02.428408 [DEBUG] switch_core_state_machine.c:371 (sofia/external/7777) State EXECUTE going to sleep
2011-08-22 04:45:02.428408 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_HANGUP
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:565 (sofia/external/7777) State HANGUP
2011-08-22 04:45:02.429464 [DEBUG] mod_sofia.c:451 sofia/external/7777 Overriding SIP cause 480 with 200 from the other leg
2011-08-22 04:45:02.429464 [DEBUG] mod_sofia.c:457 Channel sofia/external/7777 hanging up, cause: NORMAL_CLEARING
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:46 sofia/external/7777 Standard HANGUP, cause: NORMAL_CLEARING
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:565 (sofia/external/7777) State HANGUP going to sleep
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:356 (sofia/external/7777) State Change CS_HANGUP -> CS_REPORTING
2011-08-22 04:45:02.429464 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:325 (sofia/external/7777) Running State Change CS_REPORTING
2011-08-22 04:45:02.429464 [DEBUG] switch_core_state_machine.c:625 (sofia/external/7777) State REPORTING
2011-08-22 04:45:02.473396 [DEBUG] switch_core_state_machine.c:53 sofia/external/7777 Standard REPORTING, cause: NORMAL_CLEARING
2011-08-22 04:45:02.473396 [DEBUG] switch_core_state_machine.c:625 (sofia/external/7777) State REPORTING going to sleep
2011-08-22 04:45:02.473396 [DEBUG] switch_core_state_machine.c:350 (sofia/external/7777) State Change CS_REPORTING -> CS_DESTROY
2011-08-22 04:45:02.473396 [DEBUG] switch_core_session.c:1114 Send signal sofia/external/7777 [BREAK]
2011-08-22 04:45:02.473396 [DEBUG] switch_core_session.c:1286 Session 529 (sofia/external/7777) Locked, Waiting on external entities
2011-08-22 04:45:02.473396 [NOTICE] switch_core_session.c:1304 Session 529 (sofia/external/7777) Ended
2011-08-22 04:45:02.473396 [NOTICE] switch_core_session.c:1306 Close Channel sofia/external/7777 [CS_DESTROY]
2011-08-22 04:45:02.473396 [DEBUG] switch_core_state_machine.c:454 (sofia/external/7777) Callstate Change HANGUP -> DOWN

-- 
Best regards,
 sucsession                          mailto:norstar at bigmir.net