Hi,

Before sending this request, I searched the forum for similar posts and found some solutions for recent versions of Freeswitch. Unfortunately one of my servers is using Freswitch 1.0.5. In this, I am trying to make a call using “originate sofia/external/01183035887@135.xxx.xxx.xxx 9999” command at console. This is giving an error “Invalid Application hash” and FS is sending CANCEL.

Is there any way to resolve this issue other than upgrading to the latest version of Freeswitch (I am going to upgrade in coming weeks, but I need to test a specific scenario now on existing system).

Thanks & Regards

Suneel

Here is the log for reference…

2011-10-13 16:59:34.341952 [DEBUG] sofia.c:4158 Channel sofia/external/01183035887@135.196.132.200 entering state [ready][200]

2011-10-13 16:59:34.341952 [DEBUG] sofia_glue.c:3579 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20]

2011-10-13 16:59:34.341952 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/01183035887@135.196.132.200 PCMU/8000 20 ms 160 samples

2011-10-13 16:59:34.341952 [DEBUG] sofia_glue.c:3512 Set 2833 dtmf recv payload to 0

2011-10-13 16:59:34.341952 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/external/01183035887@135.196.132.200] 192.168.20.240 port 25744 -> 135.196.145.145 port 54206 codec: 0 ms: 20

2011-10-13 16:59:34.341952 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms

2011-10-13 16:59:34.341952 [NOTICE] sofia.c:4738 Channel [sofia/external/01183035887@135.196.132.200] has been answered

2011-10-13 16:59:34.341952 [DEBUG] switch_ivr_originate.c:3148 Originate Resulted in Success: [sofia/external/01183035887@135.196.132.200]

2011-10-13 16:59:34.341952 [DEBUG] switch_ivr.c:1441 (sofia/external/01183035887@135.196.132.200) State Change CS_CONSUME_MEDIA -> CS_ROUTING

2011-10-13 16:59:34.341952 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.341952 [DEBUG] switch_core_session.c:638 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.341952 [NOTICE] switch_ivr.c:1447 Transfer sofia/external/01183035887@135.196.132.200 to XML[9999@default]

2011-10-13 16:59:34.341952 [DEBUG] switch_core_state_machine.c:314 (sofia/external/01183035887@135.196.132.200) Running State Change CS_ROUTING

2011-10-13 16:59:34.341952 [DEBUG] switch_core_state_machine.c:341 (sofia/external/01183035887@135.196.132.200) State ROUTING

2011-10-13 16:59:34.341952 [DEBUG] mod_sofia.c:141 sofia/external/01183035887@135.196.132.200 SOFIA ROUTING

2011-10-13 16:59:34.341952 [DEBUG] switch_core_state_machine.c:77 sofia/external/01183035887@135.196.132.200 Standard ROUTING

2011-10-13 16:59:34.341952 [INFO] mod_dialplan_xml.c:418 Processing ->9999 in context default

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->unloop] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->tod_example] continue=true

Dialplan: day of week[5] =~ 2-6 (PASS)

Dialplan: hour[16] =~ 9-18 (PASS)

Dialplan: sofia/external/01183035887@135.196.132.200 Date/Time Match (PASS) [tod_example] break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 Action set(open=true)

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->holiday_example] continue=true

Dialplan: month[10] =~ 1 (FAIL)

Dialplan: sofia/external/01183035887@135.196.132.200 Date/Time Match (FAIL) [holiday_example] break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->global-intercept] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [global-intercept] destination_number(9999) =~ /^886$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->group-intercept] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [group-intercept] destination_number(9999) =~ /^\*8$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->intercept-ext] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [intercept-ext] destination_number(9999) =~ /^\*\*(\d+)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->redial] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [redial] destination_number(9999) =~ /^870$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->global] continue=true

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never

Dialplan: sofia/external/01183035887@135.196.132.200 Absolute Condition [global]

Dialplan: sofia/external/01183035887@135.196.132.200 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})

Dialplan: sofia/external/01183035887@135.196.132.200 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

Dialplan: sofia/external/01183035887@135.196.132.200 Action hash(insert/${domain_name}-last_dial/global/${uuid})

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->snom-demo-2] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [snom-demo-2] destination_number(9999) =~ /^9001$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->snom-demo-1] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [snom-demo-1] destination_number(9999) =~ /^9000$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->eavesdrop] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [eavesdrop] destination_number(9999) =~ /^88(.*)$|^\*0(.*)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->eavesdrop] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [eavesdrop] destination_number(9999) =~ /^779$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->call_return] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [call_return] destination_number(9999) =~ /^\*69$|^869$|^lcr$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->del-group] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [del-group] destination_number(9999) =~ /^80(\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->add-group] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [add-group] destination_number(9999) =~ /^81(\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->call-group-simo] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [call-group-simo] destination_number(9999) =~ /^82(\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->call-group-order] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [call-group-order] destination_number(9999) =~ /^83(\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->extension-intercom] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [extension-intercom] destination_number(9999) =~ /^8(10[01][0-9])$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->Local_Extension] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [Local_Extension] destination_number(9999) =~ /^(10[01][0-9])$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->group_dial_sales] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [group_dial_sales] destination_number(9999) =~ /^2000$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->group_dial_support] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [group_dial_support] destination_number(9999) =~ /^2001$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->group_dial_billing] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [group_dial_billing] destination_number(9999) =~ /^2002$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->operator] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [operator] destination_number(9999) =~ /^(operator|0)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->vmain] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [vmain] destination_number(9999) =~ /^vmain$|^4000$|^\*98$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->sip_uri] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [sip_uri] destination_number(9999) =~ /^sip:(.*)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->nb_conferences] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [nb_conferences] destination_number(9999) =~ /^(30\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->wb_conferences] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [wb_conferences] destination_number(9999) =~ /^(31\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->uwb_conferences] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [uwb_conferences] destination_number(9999) =~ /^(32\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->cdquality_conferences] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [cdquality_conferences] destination_number(9999) =~ /^(33\d{2})$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->freeswitch_public_conf_via_sip] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(9999) =~ /^9(888|1616|3232)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->mad_boss_intercom] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [mad_boss_intercom] destination_number(9999) =~ /^0911$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->mad_boss_intercom] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [mad_boss_intercom] destination_number(9999) =~ /^0912$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->mad_boss] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [mad_boss] destination_number(9999) =~ /^0913$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ivr_demo] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ivr_demo] destination_number(9999) =~ /^5000$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->dynamic_conference] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [dynamic_conference] destination_number(9999) =~ /^5001$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->rtp_multicast_page] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [rtp_multicast_page] destination_number(9999) =~ /^pagegroup$|^7243$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->park] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [park] destination_number(9999) =~ /^5900$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->unpark] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [unpark] destination_number(9999) =~ /^5901$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->valet_park] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [valet_park] destination_number(9999) =~ /^(6000)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->valet_park] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [valet_park] destination_number(9999) =~ /^(60\d[1-9])$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->park] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [park] source(..\..\src\switch_ivr_originate.c) =~ /mod_sofia/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->unpark] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [unpark] source(..\..\src\switch_ivr_originate.c) =~ /mod_sofia/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->park] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [park] source(..\..\src\switch_ivr_originate.c) =~ /mod_sofia/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->unpark] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [unpark] source(..\..\src\switch_ivr_originate.c) =~ /mod_sofia/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->wait] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [wait] destination_number(9999) =~ /^wait$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->fax_receive] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [fax_receive] destination_number(9999) =~ /^9978$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->fax_transmit] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [fax_transmit] destination_number(9999) =~ /^9979$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ringback_180] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ringback_180] destination_number(9999) =~ /^9980$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ringback_183_uk_ring] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ringback_183_uk_ring] destination_number(9999) =~ /^9981$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ringback_183_music_ring] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ringback_183_music_ring] destination_number(9999) =~ /^9982$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ringback_post_answer_uk_ring] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(9999) =~ /^9983$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ringback_post_answer_music] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ringback_post_answer_music] destination_number(9999) =~ /^9984$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->ClueCon] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [ClueCon] destination_number(9999) =~ /^9991$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->show_info] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [show_info] destination_number(9999) =~ /^9992$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->video_record] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [video_record] destination_number(9999) =~ /^9993$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->video_playback] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [video_playback] destination_number(9999) =~ /^9994$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->delay_echo] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [delay_echo] destination_number(9999) =~ /^9995$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->echo] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [echo] destination_number(9999) =~ /^9996$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->milliwatt] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [milliwatt] destination_number(9999) =~ /^9997$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->tone_stream] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [tone_stream] destination_number(9999) =~ /^9998$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->zrtp_enrollement] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [zrtp_enrollement] destination_number(9999) =~ /^9787$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 parsing [default->hold_music] continue=false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (PASS) [hold_music] destination_number(9999) =~ /^9999$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 Regex (FAIL) [hold_music] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=on-false

Dialplan: sofia/external/01183035887@135.196.132.200 ANTI-Action set(zrtp_secure_media=true)

Dialplan: sofia/external/01183035887@135.196.132.200 ANTI-Action answer()

Dialplan: sofia/external/01183035887@135.196.132.200 ANTI-Action playback(silence_stream://2000)

Dialplan: sofia/external/01183035887@135.196.132.200 ANTI-Action execute_extension(is_zrtp_secure XML features)

Dialplan: sofia/external/01183035887@135.196.132.200 ANTI-Action playback(local_stream://moh)

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:119 (sofia/external/01183035887@135.196.132.200) State Change CS_ROUTING -> CS_EXECUTE

2011-10-13 16:59:34.357577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:341 (sofia/external/01183035887@135.196.132.200) State ROUTING going to sleep

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:314 (sofia/external/01183035887@135.196.132.200) Running State Change CS_EXECUTE

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:348 (sofia/external/01183035887@135.196.132.200) State EXECUTE

2011-10-13 16:59:34.357577 [DEBUG] mod_sofia.c:227 sofia/external/01183035887@135.196.132.200 SOFIA EXECUTE

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:157 sofia/external/01183035887@135.196.132.200 Standard EXECUTE

EXECUTE sofia/external/01183035887@135.196.132.200 set(open=true)

2011-10-13 16:59:34.357577 [DEBUG] mod_dptools.c:814 sofia/external/01183035887@135.196.132.200 SET [open]=[true]

2011-10-13 16:59:34.357577 [ERR] switch_core_session.c:1727 Invalid Application hash

2011-10-13 16:59:34.357577 [NOTICE] switch_core_session.c:1728 Hangup sofia/external/01183035887@135.196.132.200 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]

2011-10-13 16:59:34.357577 [DEBUG] switch_channel.c:2071 Send signal sofia/external/01183035887@135.196.132.200 [KILL]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:348 (sofia/external/01183035887@135.196.132.200) State EXECUTE going to sleep

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:314 (sofia/external/01183035887@135.196.132.200) Running State Change CS_HANGUP

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:499 (sofia/external/01183035887@135.196.132.200) State HANGUP

2011-10-13 16:59:34.357577 [DEBUG] mod_sofia.c:412 Channel sofia/external/01183035887@135.196.132.200 hanging up, cause: DESTINATION_OUT_OF_ORDER

2011-10-13 16:59:34.357577 [DEBUG] mod_sofia.c:455 Sending BYE to sofia/external/01183035887@135.196.132.200

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:46 sofia/external/01183035887@135.196.132.200 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:499 (sofia/external/01183035887@135.196.132.200) State HANGUP going to sleep

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:333 (sofia/external/01183035887@135.196.132.200) State Change CS_HANGUP -> CS_REPORTING

2011-10-13 16:59:34.357577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:314 (sofia/external/01183035887@135.196.132.200) Running State Change CS_REPORTING

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:590 (sofia/external/01183035887@135.196.132.200) State REPORTING

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:53 sofia/external/01183035887@135.196.132.200 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:590 (sofia/external/01183035887@135.196.132.200) State REPORTING going to sleep

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:327 (sofia/external/01183035887@135.196.132.200) State Change CS_REPORTING -> CS_DESTROY

2011-10-13 16:59:34.357577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_session.c:1161 Session 20 (sofia/external/01183035887@135.196.132.200) Locked, Waiting on external entities

2011-10-13 16:59:34.357577 [NOTICE] switch_core_session.c:1179 Session 20 (sofia/external/01183035887@135.196.132.200) Ended

2011-10-13 16:59:34.357577 [NOTICE] switch_core_session.c:1181 Close Channel sofia/external/01183035887@135.196.132.200 [CS_DESTROY]

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:428 (sofia/external/01183035887@135.196.132.200) Running State Change CS_DESTROY

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:439 (sofia/external/01183035887@135.196.132.200) State DESTROY

2011-10-13 16:59:34.357577 [DEBUG] mod_sofia.c:339 sofia/external/01183035887@135.196.132.200 SOFIA DESTROY

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:60 sofia/external/01183035887@135.196.132.200 Standard DESTROY

2011-10-13 16:59:34.357577 [DEBUG] switch_core_state_machine.c:439 (sofia/external/01183035887@135.196.132.200) State DESTROY going to sleep

2011-10-13 16:59:34.466952 [DEBUG] switch_core_session.c:856 Send signal sofia/external/1183035901@135.196.132.200:5060 [BREAK]

2011-10-13 16:59:34.482577 [DEBUG] switch_ivr.c:551 sofia/external/1183035901@135.196.132.200:5060 Command Execute redirect(sip:01183035878@135.196.132.200)

EXECUTE sofia/external/1183035901@135.196.132.200:5060 redirect(sip:01183035878@135.196.132.200)

2011-10-13 16:59:34.482577 [DEBUG] mod_sofia.c:1660 Redirecting to "unknown" <sip:01183035878@135.196.132.200>

2011-10-13 16:59:34.482577 [DEBUG] switch_core_session.c:638 Send signal sofia/external/1183035901@135.196.132.200:5060 [BREAK]

2011-10-13 16:59:34.482577 [DEBUG] sofia.c:4158 Channel sofia/external/1183035901@135.196.132.200:5060 entering state [terminated][302]

2011-10-13 16:59:34.482577 [NOTICE] switch_core_state_machine.c:185 sofia/external/1183035901@135.196.132.200:5060 has executed the last dialplan instruction, hanging up.

2011-10-13 16:59:34.482577 [NOTICE] switch_core_state_machine.c:187 Hangup sofia/external/1183035901@135.196.132.200:5060 [CS_EXECUTE] [NORMAL_CLEARING]

2011-10-13 16:59:34.482577 [DEBUG] switch_channel.c:2071 Send signal sofia/external/1183035901@135.196.132.200:5060 [KILL]

2011-10-13 16:59:34.482577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/1183035901@135.196.132.200:5060 [BREAK]

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:348 (sofia/external/1183035901@135.196.132.200:5060) State EXECUTE going to sleep

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:314 (sofia/external/1183035901@135.196.132.200:5060) Running State Change CS_HANGUP

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:499 (sofia/external/1183035901@135.196.132.200:5060) State HANGUP

2011-10-13 16:59:34.482577 [DEBUG] mod_sofia.c:406 sofia/external/1183035901@135.196.132.200:5060 Overriding SIP cause 480 with 302 from the other leg

2011-10-13 16:59:34.482577 [DEBUG] mod_sofia.c:412 Channel sofia/external/1183035901@135.196.132.200:5060 hanging up, cause: NORMAL_CLEARING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:46 sofia/external/1183035901@135.196.132.200:5060 Standard HANGUP, cause: NORMAL_CLEARING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:499 (sofia/external/1183035901@135.196.132.200:5060) State HANGUP going to sleep

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:333 (sofia/external/1183035901@135.196.132.200:5060) State Change CS_HANGUP -> CS_REPORTING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/1183035901@135.196.132.200:5060 [BREAK]

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:314 (sofia/external/1183035901@135.196.132.200:5060) Running State Change CS_REPORTING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:590 (sofia/external/1183035901@135.196.132.200:5060) State REPORTING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:53 sofia/external/1183035901@135.196.132.200:5060 Standard REPORTING, cause: NORMAL_CLEARING

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:590 (sofia/external/1183035901@135.196.132.200:5060) State REPORTING going to sleep

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:327 (sofia/external/1183035901@135.196.132.200:5060) State Change CS_REPORTING -> CS_DESTROY

2011-10-13 16:59:34.482577 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/1183035901@135.196.132.200:5060 [BREAK]

2011-10-13 16:59:34.482577 [DEBUG] switch_core_session.c:1161 Session 23 (sofia/external/1183035901@135.196.132.200:5060) Locked, Waiting on external entities

2011-10-13 16:59:34.482577 [NOTICE] switch_core_session.c:1179 Session 23 (sofia/external/1183035901@135.196.132.200:5060) Ended

2011-10-13 16:59:34.482577 [NOTICE] switch_core_session.c:1181 Close Channel sofia/external/1183035901@135.196.132.200:5060 [CS_DESTROY]

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:428 (sofia/external/1183035901@135.196.132.200:5060) Running State Change CS_DESTROY

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:439 (sofia/external/1183035901@135.196.132.200:5060) State DESTROY

2011-10-13 16:59:34.482577 [DEBUG] mod_sofia.c:339 sofia/external/1183035901@135.196.132.200:5060 SOFIA DESTROY

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:60 sofia/external/1183035901@135.196.132.200:5060 Standard DESTROY

2011-10-13 16:59:34.482577 [DEBUG] switch_core_state_machine.c:439 (sofia/external/1183035901@135.196.132.200:5060) State DESTROY going to sleep

2011-10-13 16:59:34.498202 [NOTICE] switch_channel.c:669 New Channel sofia/external/01183035887@135.196.132.200 [60e6e03d-75cc-4081-8073-ef8288d472d0]

2011-10-13 16:59:34.498202 [DEBUG] mod_sofia.c:3457 (sofia/external/01183035887@135.196.132.200) State Change CS_NEW -> CS_INIT

2011-10-13 16:59:34.498202 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.529452 [DEBUG] switch_core_state_machine.c:314 (sofia/external/01183035887@135.196.132.200) Running State Change CS_INIT

2011-10-13 16:59:34.529452 [DEBUG] switch_core_state_machine.c:338 (sofia/external/01183035887@135.196.132.200) State INIT

2011-10-13 16:59:34.529452 [DEBUG] mod_sofia.c:84 sofia/external/01183035887@135.196.132.200 SOFIA INIT

2011-10-13 16:59:34.529452 [DEBUG] mod_sofia.c:118 (sofia/external/01183035887@135.196.132.200) State Change CS_INIT -> CS_ROUTING

2011-10-13 16:59:34.529452 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/01183035887@135.196.132.200 [BREAK]

2011-10-13 16:59:34.529452 [DEBUG] switch_core_state_machine.c:338 (sofia/external/01183035887@135.196.132.200) State INIT going to sleep