Прошу помощи в настройке соединения Avaya + FreeSwith по sip-транку.
На авае настроен сип-транк без авторизации. На фрисвитч:
Inbound:
Код: Выделить всё
<extension name="avaya" >
<condition field="context" expression="public"/>
<condition field="destination_number" expression="^500$">
<action application="transfer" data="114 XML default"/>
</condition>
</extension>Код: Выделить всё
<extension name="192.168.10.13.9.3d" >
<condition field="destination_number" expression="^9(\d{3})$" >
<action application="set" data="sip_h_X-accountcode=${accountcode}" />
<action application="set" data="call_direction=outbound" />
<action application="set" data="hangup_after_bridge=true" />
<action application="set" data="effective_caller_id_name=${outbound_caller_id_name}" />
<action application="set" data="effective_caller_id_number=${outbound_caller_id_number}" />
<action application="set" data="inherit_codec=true" />
<action application="bridge" data="sofia/gateway/192.168.10.13/$1" />
</condition>
</extension>Код: Выделить всё
<include>
<gateway name="192.168.10.13">
<param name="username" value="register:false"/>
<param name="password" value="register:false"/>
<param name="expire-seconds" value="800"/>
<param name="register" value="false"/>
<param name="retry-seconds" value="60"/>
<param name="context" value="public"/>
</gateway>
</include>Когда вызываешь с аваи номер FS, звонок проходит нормально, обе стороны слышат друг-друга и звонок завершается штатно. FS и Avaya находятся в разных сетях, соединённых по впн. Маршрутизацию проверил, пакеты ходят как надо. Телефоны тоже получают правильные настройки.
Тассировка звонка с FS:
Код: Выделить всё
2014-10-31 10:44:16.120476 [DEBUG] sofia.c:7460 IP 192.168.5.24 Rejected by acl "domains". Falling back to Digest auth.
2014-10-31 10:44:16.120476 [WARNING] sofia_reg.c:1432 SIP auth challenge (INVITE) on sofia profile 'internal' for [9500@192.168.5.22] from ip 192.168.5.24
2014-10-31 10:44:16.284539 [DEBUG] sofia.c:7460 IP 192.168.5.24 Rejected by acl "domains". Falling back to Digest auth.
2014-10-31 10:44:16.284539 [NOTICE] switch_channel.c:924 New Channel sofia/internal/114@192.168.5.22 [6e719cff-3c95-4784-9bd5-e6f70e84c493]
2014-10-31 10:44:16.284539 [DEBUG] sofia.c:5431 Channel sofia/internal/114@192.168.5.22 entering state [received][100]
2014-10-31 10:44:16.284539 [DEBUG] sofia.c:5442 Remote SDP:
v=0
o=- 20026 20026 IN IP4 192.168.5.24
s=SDP data
c=IN IP4 192.168.5.24
t=0 0
m=audio 11788 RTP/AVP 9 4 2 106 18 0 8 101
a=rtpmap:9 G722/8000
a=rtpmap:4 G723/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:106 iLBC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
2014-10-31 10:44:16.284539 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:115:32000:20:48000]
2014-10-31 10:44:16.284539 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:107:16000:20:32000]
2014-10-31 10:44:16.284539 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [G722:9:8000:20:64000]/[G722:9:8000:20:64000]
2014-10-31 10:44:16.284539 [DEBUG] sofia_glue.c:2888 Set Codec sofia/internal/114@192.168.5.22 G722/8000 20 ms 160 samples 64000 bits
2014-10-31 10:44:16.284539 [DEBUG] sofia_glue.c:4888 Set 2833 dtmf send/recv payload to 101
2014-10-31 10:44:16.284539 [DEBUG] sofia.c:5654 (sofia/internal/114@192.168.5.22) State Change CS_NEW -> CS_INIT
2014-10-31 10:44:16.284539 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/114@192.168.5.22) Running State Change CS_INIT
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/114@192.168.5.22) State INIT
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:85 sofia/internal/114@192.168.5.22 SOFIA INIT
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:125 (sofia/internal/114@192.168.5.22) State Change CS_INIT -> CS_ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/114@192.168.5.22) State INIT going to sleep
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/114@192.168.5.22) Running State Change CS_ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_channel.c:1884 (sofia/internal/114@192.168.5.22) Callstate Change DOWN -> RINGING
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/114@192.168.5.22) State ROUTING
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:148 sofia/internal/114@192.168.5.22 SOFIA ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:104 sofia/internal/114@192.168.5.22 Standard ROUTING
2014-10-31 10:44:16.307976 [INFO] mod_dialplan_xml.c:481 Processing 114 <114>->9500 in context default
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->unloop] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->call_direction] continue=true
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [call_direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 ANTI-Action set(call_direction=local)
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/114@192.168.5.22 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/114@192.168.5.22 Action set(open=true)
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/114@192.168.5.22 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [group-intercept] destination_number(9500) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->redial] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [redial] destination_number(9500) =~ /^(redial|\*870)$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->global] continue=true
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/internal/114@192.168.5.22 Absolute Condition [global]
Dialplan: sofia/internal/114@192.168.5.22 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/114@192.168.5.22 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/114@192.168.5.22 Action hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/114@192.168.5.22 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [snom-demo-2] destination_number(9500) =~ /^\*9001$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [snom-demo-1] destination_number(9500) =~ /^\*9000$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->call_privacy] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [call_privacy] destination_number(9500) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->call_return] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [call_return] destination_number(9500) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->del-group] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [del-group] destination_number(9500) =~ /^\*\*80(\d{2})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->add-group] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [add-group] destination_number(9500) =~ /^\*\*81(\d{2})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [call-group-simo] destination_number(9500) =~ /^\*\*82(\d{2})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [call-group-order] destination_number(9500) =~ /^\*83(\d{2})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [intercept-ext] destination_number(9500) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [Local_Extension_Skinny] destination_number(9500) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->send_to_voicemail] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (FAIL) [send_to_voicemail] destination_number(9500) =~ /^\*99(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 parsing [default->192.168.10.13.9.3d] continue=false
Dialplan: sofia/internal/114@192.168.5.22 Regex (PASS) [192.168.10.13.9.3d] destination_number(9500) =~ /^9(\d{3})$/ break=on-false
Dialplan: sofia/internal/114@192.168.5.22 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/114@192.168.5.22 Action set(call_direction=outbound)
Dialplan: sofia/internal/114@192.168.5.22 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/114@192.168.5.22 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/114@192.168.5.22 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/114@192.168.5.22 Action set(inherit_codec=true)
Dialplan: sofia/internal/114@192.168.5.22 Action bridge(sofia/gateway/192.168.10.13/500)
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/114@192.168.5.22) State Change CS_ROUTING -> CS_EXECUTE
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/114@192.168.5.22) State ROUTING going to sleep
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/114@192.168.5.22) Running State Change CS_EXECUTE
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/114@192.168.5.22) State EXECUTE
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:241 sofia/internal/114@192.168.5.22 SOFIA EXECUTE
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:192 sofia/internal/114@192.168.5.22 Standard EXECUTE
EXECUTE sofia/internal/114@192.168.5.22 set(call_direction=local)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [call_direction]=[local]
EXECUTE sofia/internal/114@192.168.5.22 set(open=true)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [open]=[true]
EXECUTE sofia/internal/114@192.168.5.22 hash(insert/192.168.5.22-spymap/114/6e719cff-3c95-4784-9bd5-e6f70e84c493)
EXECUTE sofia/internal/114@192.168.5.22 hash(insert/192.168.5.22-last_dial/114/9500)
EXECUTE sofia/internal/114@192.168.5.22 hash(insert/192.168.5.22-last_dial/global/6e719cff-3c95-4784-9bd5-e6f70e84c493)
EXECUTE sofia/internal/114@192.168.5.22 set(RFC2822_DATE=Fri, 31 Oct 2014 10:44:16 Russia TZ 2 Standard Time)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [RFC2822_DATE]=[Fri, 31 Oct 2014 10:44:16 Russia TZ 2 Standard Time]
EXECUTE sofia/internal/114@192.168.5.22 set(sip_h_X-accountcode=)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/internal/114@192.168.5.22 set(call_direction=outbound)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [call_direction]=[outbound]
EXECUTE sofia/internal/114@192.168.5.22 set(hangup_after_bridge=true)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/114@192.168.5.22 set(effective_caller_id_name=)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/internal/114@192.168.5.22 set(effective_caller_id_number=)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/internal/114@192.168.5.22 set(inherit_codec=true)
2014-10-31 10:44:16.307976 [DEBUG] mod_dptools.c:1286 sofia/internal/114@192.168.5.22 SET [inherit_codec]=[true]
EXECUTE sofia/internal/114@192.168.5.22 bridge(sofia/gateway/192.168.10.13/500)
2014-10-31 10:44:16.307976 [DEBUG] switch_channel.c:1045 sofia/internal/114@192.168.5.22 EXPORTING[export_vars] [domain_name]=[192.168.5.22] to event
2014-10-31 10:44:16.307976 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables
2014-10-31 10:44:16.307976 [NOTICE] switch_channel.c:924 New Channel sofia/external/500 [1af8e612-ca17-4380-b677-3d2198102b33]
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:4646 (sofia/external/500) State Change CS_NEW -> CS_INIT
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/external/500) Running State Change CS_INIT
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:401 (sofia/external/500) State INIT
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:85 sofia/external/500 SOFIA INIT
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:875 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:125 (sofia/external/500) State Change CS_INIT -> CS_ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:401 (sofia/external/500) State INIT going to sleep
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/external/500) Running State Change CS_ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_channel.c:1884 (sofia/external/500) Callstate Change DOWN -> RINGING
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:410 (sofia/external/500) State ROUTING
2014-10-31 10:44:16.307976 [DEBUG] mod_sofia.c:148 sofia/external/500 SOFIA ROUTING
2014-10-31 10:44:16.307976 [DEBUG] switch_ivr_originate.c:66 (sofia/external/500) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2014-10-31 10:44:16.307976 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:410 (sofia/external/500) State ROUTING going to sleep
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:362 (sofia/external/500) Running State Change CS_CONSUME_MEDIA
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:429 (sofia/external/500) State CONSUME_MEDIA
2014-10-31 10:44:16.307976 [DEBUG] switch_core_state_machine.c:429 (sofia/external/500) State CONSUME_MEDIA going to sleep
2014-10-31 10:44:16.307976 [DEBUG] sofia.c:5431 Channel sofia/external/500 entering state [calling][0]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] sofia.c:5431 Channel sofia/internal/114@192.168.5.22 entering state [terminated][487]
2014-10-31 10:44:28.149773 [DEBUG] switch_channel.c:2846 (sofia/internal/114@192.168.5.22) Callstate Change RINGING -> HANGUP
2014-10-31 10:44:28.149773 [NOTICE] sofia.c:6195 Hangup sofia/internal/114@192.168.5.22 [CS_EXECUTE] [ORIGINATOR_CANCEL]
2014-10-31 10:44:28.149773 [DEBUG] switch_channel.c:2869 Send signal sofia/internal/114@192.168.5.22 [KILL]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_channel.c:2846 (sofia/external/500) Callstate Change RINGING -> HANGUP
2014-10-31 10:44:28.149773 [NOTICE] switch_ivr_originate.c:3182 Hangup sofia/external/500 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2014-10-31 10:44:28.149773 [DEBUG] switch_channel.c:2869 Send signal sofia/external/500 [KILL]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_ivr_originate.c:3358 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2014-10-31 10:44:28.149773 [INFO] mod_dptools.c:2920 Originate Failed. Cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:2285 sofia/internal/114@192.168.5.22 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/114@192.168.5.22) State EXECUTE going to sleep
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/114@192.168.5.22) Running State Change CS_HANGUP
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/114@192.168.5.22) State HANGUP
2014-10-31 10:44:28.149773 [DEBUG] mod_sofia.c:463 sofia/internal/114@192.168.5.22 Overriding SIP cause 487 with 487 from the other leg
2014-10-31 10:44:28.149773 [DEBUG] mod_sofia.c:469 Channel sofia/internal/114@192.168.5.22 hanging up, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:47 sofia/internal/114@192.168.5.22 Standard HANGUP, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/114@192.168.5.22) State HANGUP going to sleep
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/114@192.168.5.22) State Change CS_HANGUP -> CS_REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/114@192.168.5.22) Running State Change CS_REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/114@192.168.5.22) State REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:362 (sofia/external/500) Running State Change CS_HANGUP
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:602 (sofia/external/500) State HANGUP
2014-10-31 10:44:28.149773 [DEBUG] mod_sofia.c:469 Channel sofia/external/500 hanging up, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] mod_sofia.c:523 Sending CANCEL to sofia/external/500
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:47 sofia/external/500 Standard HANGUP, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:602 (sofia/external/500) State HANGUP going to sleep
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:393 (sofia/external/500) State Change CS_HANGUP -> CS_REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:362 (sofia/external/500) Running State Change CS_REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:662 (sofia/external/500) State REPORTING
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:79 sofia/external/500 Standard REPORTING, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:662 (sofia/external/500) State REPORTING going to sleep
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:387 (sofia/external/500) State Change CS_REPORTING -> CS_DESTROY
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/500 [BREAK]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_session.c:1380 Session 49 (sofia/external/500) Locked, Waiting on external entities
2014-10-31 10:44:28.149773 [NOTICE] switch_core_session.c:1398 Session 49 (sofia/external/500) Ended
2014-10-31 10:44:28.149773 [NOTICE] switch_core_session.c:1400 Close Channel sofia/external/500 [CS_DESTROY]
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:491 (sofia/external/500) Callstate Change HANGUP -> DOWN
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:494 (sofia/external/500) Running State Change CS_DESTROY
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:504 (sofia/external/500) State DESTROY
2014-10-31 10:44:28.149773 [DEBUG] mod_sofia.c:374 sofia/external/500 SOFIA DESTROY
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:86 sofia/external/500 Standard DESTROY
2014-10-31 10:44:28.149773 [DEBUG] switch_core_state_machine.c:504 (sofia/external/500) State DESTROY going to sleep
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:79 sofia/internal/114@192.168.5.22 Standard REPORTING, cause: ORIGINATOR_CANCEL
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/114@192.168.5.22) State REPORTING going to sleep
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/114@192.168.5.22) State Change CS_REPORTING -> CS_DESTROY
2014-10-31 10:44:28.219109 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/114@192.168.5.22 [BREAK]
2014-10-31 10:44:28.219109 [DEBUG] switch_core_session.c:1380 Session 48 (sofia/internal/114@192.168.5.22) Locked, Waiting on external entities
2014-10-31 10:44:28.219109 [NOTICE] switch_core_session.c:1398 Session 48 (sofia/internal/114@192.168.5.22) Ended
2014-10-31 10:44:28.219109 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/114@192.168.5.22 [CS_DESTROY]
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/114@192.168.5.22) Callstate Change HANGUP -> DOWN
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/114@192.168.5.22) Running State Change CS_DESTROY
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/114@192.168.5.22) State DESTROY
2014-10-31 10:44:28.219109 [DEBUG] mod_sofia.c:374 sofia/internal/114@192.168.5.22 SOFIA DESTROY
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:86 sofia/internal/114@192.168.5.22 Standard DESTROY
2014-10-31 10:44:28.219109 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/114@192.168.5.22) State DESTROY going to sleep
2014-10-31 10:52:41.426546 [WARNING] sofia_reg.c:1432 SIP auth challenge (REGISTER) on sofia profile 'internal' for [114@192.168.5.22] from ip 192.168.5.24