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: Thanks. 2011-08-22 04:44:54.970599 [WARNING] sofia_reg.c:1241 SIP auth challenge (REGISTER) on sofia profile 'internal' for [8100@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@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 Vasya <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@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@10.160.0.3] 2011-08-22 04:45:00.730678 [NOTICE] switch_channel.c:816 New Channel sofia/internal/sip:8100@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@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@10.160.1.212:5060 [BREAK] 2011-08-22 04:45:00.730678 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100@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@10.160.1.212:5060) State INIT 2011-08-22 04:45:00.730678 [DEBUG] mod_sofia.c:84 sofia/internal/sip:8100@10.160.1.212:5060 SOFIA INIT 2011-08-22 04:45:00.731677 [DEBUG] mod_sofia.c:124 (sofia/internal/sip:8100@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@10.160.1.212:5060 [BREAK] 2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:361 (sofia/internal/sip:8100@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@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@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@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@10.160.1.212:5060) State ROUTING 2011-08-22 04:45:00.731677 [DEBUG] mod_sofia.c:147 sofia/internal/sip:8100@10.160.1.212:5060 SOFIA ROUTING 2011-08-22 04:45:00.731677 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:8100@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@10.160.1.212:5060 [BREAK] 2011-08-22 04:45:00.731677 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/sip:8100@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@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@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@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@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@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@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@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@10.160.1.212:5060 [BREAK] 2011-08-22 04:45:00.741675 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100@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@10.160.1.212:5060) State HANGUP 2011-08-22 04:45:00.742676 [DEBUG] mod_sofia.c:451 sofia/internal/sip:8100@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@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@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@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@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@10.160.1.212:5060 [BREAK] 2011-08-22 04:45:00.743676 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:8100@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@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@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@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@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@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@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@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@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@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@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@10.160.1.212:5060) State DESTROY 2011-08-22 04:45:00.743676 [DEBUG] mod_sofia.c:362 sofia/internal/sip:8100@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@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@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@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@bigmir.net