2012-01-06 14:46:04.271486 [NOTICE] switch_channel.c:816 New Channel sofia/ocs/+7324609096@mediation2.chao.local [fa1cf9f4-8bc8-4415-aeb8-8f536216ad69]
2012-01-06 14:46:04.274231 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_NEW
2012-01-06 14:46:04.274231 [DEBUG] switch_core_state_machine.c:343 (sofia/ocs/+7324609096@mediation2.chao.local) State NEW
2012-01-06 14:46:04.281093 [DEBUG] sofia.c:4761 Channel sofia/ocs/+7324609096@mediation2.chao.local entering state [received][100]
2012-01-06 14:46:04.281093 [DEBUG] sofia.c:4772 Remote SDP:
v=0
o=- 284 1 IN IP4 192.168.1.117
s=session
c=IN IP4 192.168.1.117
b=CT:1000
t=0 0
m=audio 51422 RTP/AVP 97 101 13 0 8
c=IN IP4 192.168.1.117
a=rtpmap:97 RED/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtcp:51423
a=label:Audio
a=ptime:20

2012-01-06 14:46:04.281093 [DEBUG] sofia.c:4923 (sofia/ocs/+7324609096@mediation2.chao.local) State Change CS_NEW -> CS_INIT
2012-01-06 14:46:04.281093 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_INIT
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:361 (sofia/ocs/+7324609096@mediation2.chao.local) State INIT
2012-01-06 14:46:04.281093 [DEBUG] mod_sofia.c:84 sofia/ocs/+7324609096@mediation2.chao.local SOFIA INIT
2012-01-06 14:46:04.281093 [DEBUG] mod_sofia.c:124 (sofia/ocs/+7324609096@mediation2.chao.local) State Change CS_INIT -> CS_ROUTING
2012-01-06 14:46:04.281093 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:361 (sofia/ocs/+7324609096@mediation2.chao.local) State INIT going to sleep
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_ROUTING
2012-01-06 14:46:04.281093 [DEBUG] switch_channel.c:1672 (sofia/ocs/+7324609096@mediation2.chao.local) Callstate Change DOWN -> RINGING
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:364 (sofia/ocs/+7324609096@mediation2.chao.local) State ROUTING
2012-01-06 14:46:04.281093 [DEBUG] mod_sofia.c:147 sofia/ocs/+7324609096@mediation2.chao.local SOFIA ROUTING
2012-01-06 14:46:04.281093 [DEBUG] switch_core_state_machine.c:77 sofia/ocs/+7324609096@mediation2.chao.local Standard ROUTING
2012-01-06 14:46:04.281093 [INFO] mod_dialplan_xml.c:331 Processing Tim Thornton <+7324609096>->+15550011749 in context from_ocs
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->detect_secure_rtp] continue=true
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [detect_secure_rtp] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->call_debug] continue=true
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [call_debug] true() =~ /^true$/ break=never
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->For_ResponsePoint_Location_79] continue=false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [For_ResponsePoint_Location_79] ${sofia_contact(branches/RP79@smartsip.branches)}(error/user_not_registered) =~ /sip:RP79\@(.*)$/ break=on-false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->bypass_list] continue=false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [bypass_list] destination_number(+15550011749) =~ /(^12027621401$)/ break=on-false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->smartsip_for_phone] continue=false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [smartsip_for_phone] ${sip_to_uri}(+15550011749@192.168.1.116) =~ /^(\d*)@smartsip\.phones/ break=on-false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->smartsip_for_branch] continue=false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (FAIL) [smartsip_for_branch] ${sip_to_uri}(+15550011749@192.168.1.116) =~ /^(\d*)@smartsip\.branches/ break=on-false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local parsing [from_ocs->smartsip_autoroute_out] continue=false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Regex (PASS) [smartsip_autoroute_out] destination_number(+15550011749) =~ /(\d+)/ break=on-false
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(ocs_non_ev_mode=) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(ocs_non_ev_gateway=) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(ocs_default_gateway=) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(exchange_um_subscriberaccess=175,886) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_dialoptions=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_locations=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_usages=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_admatches=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_callerid_names=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(smartsip_callerid_numbers=,) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(sip_ignore_183nosdp=true) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action set(bypass_media=false) 
Dialplan: sofia/ocs/+7324609096@mediation2.chao.local Action park() 
2012-01-06 14:46:04.292072 [DEBUG] switch_core_state_machine.c:119 (sofia/ocs/+7324609096@mediation2.chao.local) State Change CS_ROUTING -> CS_EXECUTE
2012-01-06 14:46:04.292072 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.292072 [DEBUG] switch_core_state_machine.c:364 (sofia/ocs/+7324609096@mediation2.chao.local) State ROUTING going to sleep
2012-01-06 14:46:04.292072 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_EXECUTE
2012-01-06 14:46:04.292072 [DEBUG] switch_core_state_machine.c:371 (sofia/ocs/+7324609096@mediation2.chao.local) State EXECUTE
2012-01-06 14:46:04.292072 [DEBUG] mod_sofia.c:240 sofia/ocs/+7324609096@mediation2.chao.local SOFIA EXECUTE
2012-01-06 14:46:04.292072 [DEBUG] switch_core_state_machine.c:157 sofia/ocs/+7324609096@mediation2.chao.local Standard EXECUTE
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(ocs_non_ev_mode=)
2012-01-06 14:46:04.294817 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [ocs_non_ev_mode]=[UNDEF]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(ocs_non_ev_gateway=)
2012-01-06 14:46:04.294817 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [ocs_non_ev_gateway]=[UNDEF]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(ocs_default_gateway=)
2012-01-06 14:46:04.297562 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [ocs_default_gateway]=[UNDEF]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(exchange_um_subscriberaccess=175,886)
2012-01-06 14:46:04.297562 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [exchange_um_subscriberaccess]=[175,886]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_dialoptions=,)
2012-01-06 14:46:04.300307 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_dialoptions]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_locations=,)
2012-01-06 14:46:04.300307 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_locations]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_usages=,)
2012-01-06 14:46:04.303052 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_usages]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_admatches=,)
2012-01-06 14:46:04.303052 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_admatches]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_callerid_names=,)
2012-01-06 14:46:04.305796 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_callerid_names]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(smartsip_callerid_numbers=,)
2012-01-06 14:46:04.305796 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [smartsip_callerid_numbers]=[,]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(sip_ignore_183nosdp=true)
2012-01-06 14:46:04.308541 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [sip_ignore_183nosdp]=[true]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local set(bypass_media=false)
2012-01-06 14:46:04.308541 [DEBUG] mod_dptools.c:1060 sofia/ocs/+7324609096@mediation2.chao.local SET [bypass_media]=[false]
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local park()
2012-01-06 14:46:04.344224 [DEBUG] switch_core_session.c:954 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.367554 [DEBUG] switch_ivr.c:564 sofia/ocs/+7324609096@mediation2.chao.local Command Execute bridge({ignore_display_updates=true,sip_contact_user=5557}sofia/phones/7324609096%192.168.1.116)
EXECUTE sofia/ocs/+7324609096@mediation2.chao.local bridge({ignore_display_updates=true,sip_contact_user=5557}sofia/phones/7324609096%192.168.1.116)
2012-01-06 14:46:04.367554 [DEBUG] switch_channel.c:922 sofia/ocs/+7324609096@mediation2.chao.local EXPORTING[export_vars] [effective_caller_id_name]=[Mike Barry] to event
2012-01-06 14:46:04.367554 [DEBUG] switch_channel.c:922 sofia/ocs/+7324609096@mediation2.chao.local EXPORTING[export_vars] [effective_caller_id_number]=[5557] to event
2012-01-06 14:46:04.367554 [DEBUG] switch_ivr_originate.c:1873 Parsing global variables
2012-01-06 14:46:04.367554 [DEBUG] switch_event.c:1170 Parsing variable [ignore_display_updates]=[true]
2012-01-06 14:46:04.367554 [DEBUG] switch_event.c:1170 Parsing variable [sip_contact_user]=[5557]
2012-01-06 14:46:04.370299 [NOTICE] switch_channel.c:816 New Channel sofia/phones/7324609096 [1bd23674-e73e-4a1a-9fc3-44bc09aad086]
2012-01-06 14:46:04.370299 [DEBUG] mod_sofia.c:4311 (sofia/phones/7324609096) State Change CS_NEW -> CS_INIT
2012-01-06 14:46:04.370299 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.370299 [DEBUG] switch_core_state_machine.c:325 (sofia/phones/7324609096) Running State Change CS_INIT
2012-01-06 14:46:04.370299 [DEBUG] switch_core_state_machine.c:361 (sofia/phones/7324609096) State INIT
2012-01-06 14:46:04.370299 [DEBUG] mod_sofia.c:84 sofia/phones/7324609096 SOFIA INIT
2012-01-06 14:46:04.373044 [DEBUG] mod_sofia.c:124 (sofia/phones/7324609096) State Change CS_INIT -> CS_ROUTING
2012-01-06 14:46:04.373044 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:361 (sofia/phones/7324609096) State INIT going to sleep
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:325 (sofia/phones/7324609096) Running State Change CS_ROUTING
2012-01-06 14:46:04.373044 [DEBUG] switch_channel.c:1672 (sofia/phones/7324609096) Callstate Change DOWN -> RINGING
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:364 (sofia/phones/7324609096) State ROUTING
2012-01-06 14:46:04.373044 [DEBUG] mod_sofia.c:147 sofia/phones/7324609096 SOFIA ROUTING
2012-01-06 14:46:04.373044 [DEBUG] switch_ivr_originate.c:66 (sofia/phones/7324609096) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-01-06 14:46:04.373044 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:364 (sofia/phones/7324609096) State ROUTING going to sleep
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:325 (sofia/phones/7324609096) Running State Change CS_CONSUME_MEDIA
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:383 (sofia/phones/7324609096) State CONSUME_MEDIA
2012-01-06 14:46:04.373044 [DEBUG] switch_core_state_machine.c:383 (sofia/phones/7324609096) State CONSUME_MEDIA going to sleep
2012-01-06 14:46:04.373044 [DEBUG] sofia.c:4761 Channel sofia/phones/7324609096 entering state [calling][0]
2012-01-06 14:46:04.405982 [DEBUG] sofia.c:4761 Channel sofia/phones/7324609096 entering state [terminated][603]
2012-01-06 14:46:04.405982 [DEBUG] switch_channel.c:2567 (sofia/phones/7324609096) Callstate Change RINGING -> HANGUP
2012-01-06 14:46:04.405982 [NOTICE] sofia.c:5407 Hangup sofia/phones/7324609096 [CS_CONSUME_MEDIA] [CALL_REJECTED]
2012-01-06 14:46:04.405982 [DEBUG] switch_channel.c:2583 Send signal sofia/phones/7324609096 [KILL]
2012-01-06 14:46:04.405982 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.405982 [DEBUG] switch_ivr_originate.c:3299 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2012-01-06 14:46:04.405982 [DEBUG] switch_core_state_machine.c:325 (sofia/phones/7324609096) Running State Change CS_HANGUP
2012-01-06 14:46:04.405982 [INFO] mod_dptools.c:2647 Originate Failed.  Cause: CALL_REJECTED
2012-01-06 14:46:04.405982 [DEBUG] switch_channel.c:2567 (sofia/ocs/+7324609096@mediation2.chao.local) Callstate Change RINGING -> HANGUP
2012-01-06 14:46:04.405982 [NOTICE] mod_dptools.c:2761 Hangup sofia/ocs/+7324609096@mediation2.chao.local [CS_EXECUTE] [CALL_REJECTED]
2012-01-06 14:46:04.405982 [DEBUG] switch_channel.c:2583 Send signal sofia/ocs/+7324609096@mediation2.chao.local [KILL]
2012-01-06 14:46:04.405982 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.405982 [DEBUG] switch_core_session.c:2060 sofia/ocs/+7324609096@mediation2.chao.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-01-06 14:46:04.405982 [DEBUG] switch_core_session.c:2060 sofia/ocs/+7324609096@mediation2.chao.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-01-06 14:46:04.405982 [DEBUG] switch_core_state_machine.c:371 (sofia/ocs/+7324609096@mediation2.chao.local) State EXECUTE going to sleep
2012-01-06 14:46:04.405982 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_HANGUP
2012-01-06 14:46:04.405982 [DEBUG] switch_core_state_machine.c:565 (sofia/phones/7324609096) State HANGUP
2012-01-06 14:46:04.405982 [DEBUG] mod_sofia.c:451 sofia/phones/7324609096 Overriding SIP cause 603 with 603 from the other leg
2012-01-06 14:46:04.405982 [DEBUG] mod_sofia.c:457 Channel sofia/phones/7324609096 hanging up, cause: CALL_REJECTED
2012-01-06 14:46:04.405982 [DEBUG] switch_core_state_machine.c:565 (sofia/ocs/+7324609096@mediation2.chao.local) State HANGUP
2012-01-06 14:46:04.405982 [DEBUG] mod_sofia.c:451 sofia/ocs/+7324609096@mediation2.chao.local Overriding SIP cause 603 with 603 from the other leg
2012-01-06 14:46:04.405982 [DEBUG] mod_sofia.c:457 Channel sofia/ocs/+7324609096@mediation2.chao.local hanging up, cause: CALL_REJECTED
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:46 sofia/phones/7324609096 Standard HANGUP, cause: CALL_REJECTED
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:565 (sofia/phones/7324609096) State HANGUP going to sleep
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:356 (sofia/phones/7324609096) State Change CS_HANGUP -> CS_REPORTING
2012-01-06 14:46:04.410099 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:325 (sofia/phones/7324609096) Running State Change CS_REPORTING
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:625 (sofia/phones/7324609096) State REPORTING
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:53 sofia/phones/7324609096 Standard REPORTING, cause: CALL_REJECTED
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:625 (sofia/phones/7324609096) State REPORTING going to sleep
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:350 (sofia/phones/7324609096) State Change CS_REPORTING -> CS_DESTROY
2012-01-06 14:46:04.410099 [DEBUG] switch_core_session.c:1116 Send signal sofia/phones/7324609096 [BREAK]
2012-01-06 14:46:04.410099 [DEBUG] switch_core_session.c:1288 Session 91 (sofia/phones/7324609096) Locked, Waiting on external entities
2012-01-06 14:46:04.410099 [NOTICE] switch_core_session.c:1306 Session 91 (sofia/phones/7324609096) Ended
2012-01-06 14:46:04.410099 [NOTICE] switch_core_session.c:1308 Close Channel sofia/phones/7324609096 [CS_DESTROY]
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:454 (sofia/phones/7324609096) Callstate Change HANGUP -> DOWN
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:457 (sofia/phones/7324609096) Running State Change CS_DESTROY
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:467 (sofia/phones/7324609096) State DESTROY
2012-01-06 14:46:04.410099 [DEBUG] mod_sofia.c:362 sofia/phones/7324609096 SOFIA DESTROY
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:60 sofia/phones/7324609096 Standard DESTROY
2012-01-06 14:46:04.410099 [DEBUG] switch_core_state_machine.c:467 (sofia/phones/7324609096) State DESTROY going to sleep
2012-01-06 14:46:04.412844 [DEBUG] mod_sofia.c:519 Responding to INVITE with: 603
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:46 sofia/ocs/+7324609096@mediation2.chao.local Standard HANGUP, cause: CALL_REJECTED
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:565 (sofia/ocs/+7324609096@mediation2.chao.local) State HANGUP going to sleep
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:356 (sofia/ocs/+7324609096@mediation2.chao.local) State Change CS_HANGUP -> CS_REPORTING
2012-01-06 14:46:04.412844 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:325 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_REPORTING
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:625 (sofia/ocs/+7324609096@mediation2.chao.local) State REPORTING
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:53 sofia/ocs/+7324609096@mediation2.chao.local Standard REPORTING, cause: CALL_REJECTED
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:625 (sofia/ocs/+7324609096@mediation2.chao.local) State REPORTING going to sleep
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:350 (sofia/ocs/+7324609096@mediation2.chao.local) State Change CS_REPORTING -> CS_DESTROY
2012-01-06 14:46:04.412844 [DEBUG] switch_core_session.c:1116 Send signal sofia/ocs/+7324609096@mediation2.chao.local [BREAK]
2012-01-06 14:46:04.412844 [DEBUG] switch_core_session.c:1288 Session 90 (sofia/ocs/+7324609096@mediation2.chao.local) Locked, Waiting on external entities
2012-01-06 14:46:04.412844 [NOTICE] switch_core_session.c:1306 Session 90 (sofia/ocs/+7324609096@mediation2.chao.local) Ended
2012-01-06 14:46:04.412844 [NOTICE] switch_core_session.c:1308 Close Channel sofia/ocs/+7324609096@mediation2.chao.local [CS_DESTROY]
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:454 (sofia/ocs/+7324609096@mediation2.chao.local) Callstate Change HANGUP -> DOWN
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:457 (sofia/ocs/+7324609096@mediation2.chao.local) Running State Change CS_DESTROY
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:467 (sofia/ocs/+7324609096@mediation2.chao.local) State DESTROY
2012-01-06 14:46:04.412844 [DEBUG] mod_sofia.c:362 sofia/ocs/+7324609096@mediation2.chao.local SOFIA DESTROY
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:60 sofia/ocs/+7324609096@mediation2.chao.local Standard DESTROY
2012-01-06 14:46:04.412844 [DEBUG] switch_core_state_machine.c:467 (sofia/ocs/+7324609096@mediation2.chao.local) State DESTROY going to sleep