Two extensions 401 and 403 are registered by Twinkle!<br>401 calls 403 but 403 does not ring and 401 does not listen ring back,<br>but FS thinks them ringing!<br>I had not this problem beforehand,<br>This server has two ethernet cards,<br>
#1 for connecting to trunk<br>and #2 for connecting to local network.(I have no problem in network routing and I've tested all things)<br>These two extensions are registered at local network (192.168.54.69)<br>What is the problem? My freeswitch log is below:<br>
<br>2013-03-12 13:56:51.741964 [NOTICE] switch_channel.c:968 New Channel sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [5ab2118c-8aff-11e2-bd72-4f1eb6844087]<br>2013-03-12 13:56:51.741964 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>
2013-03-12 13:56:51.741964 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:56:51.741964 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_NEW<br>
2013-03-12 13:56:51.741964 [DEBUG] switch_core_state_machine.c:433 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State NEW<br>2013-03-12 13:56:51.761967 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>
2013-03-12 13:56:51.761967 [DEBUG] sofia.c:1719 detaching session 5ab2118c-8aff-11e2-bd72-4f1eb6844087<br>2013-03-12 13:56:51.761967 [DEBUG] sofia.c:1811 Re-attaching to session 5ab2118c-8aff-11e2-bd72-4f1eb6844087<br>2013-03-12 13:56:51.761967 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>
2013-03-12 13:56:51.761967 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia.c:5574 Channel sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> entering state [received][100]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia.c:5585 Remote SDP:<br>v=0<br>o=twinkle 799456135 497926540 IN IP4 192.168.1.102<br>s=-<br>c=IN IP4 192.168.1.102<br>t=0 0<br>m=audio 8000 RTP/AVP 98 97 8 0 3 101<br>a=rtpmap:98 speex/16000<br>
a=rtpmap:97 speex/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:3 GSM/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br>a=ptime:20<br><br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:98:16000:20:0]/[G722:9:8000:20:64000]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:98:16000:20:0]/[PCMU:0:8000:20:64000]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:98:16000:20:0]/[PCMA:8:8000:20:64000]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:98:16000:20:0]/[GSM:3:8000:20:13200]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:97:8000:20:0]/[G722:9:8000:20:64000]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:97:8000:20:0]/[PCMU:0:8000:20:64000]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:97:8000:20:0]/[PCMA:8:8000:20:64000]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [speex:97:8000:20:0]/[GSM:3:8000:20:13200]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]<br>
2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]<br>2013-03-12 13:56:51.781957 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]<br>
2013-03-12 13:56:51.801956 [DEBUG] sofia_glue.c:3093 Set Codec sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> PCMA/8000 20 ms 160 samples 64000 bits<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Original read codec set to PCMA:8<br>
2013-03-12 13:56:51.801956 [DEBUG] sofia_glue.c:5266 Set 2833 dtmf send/recv payload to 101<br>2013-03-12 13:56:51.801956 [DEBUG] sofia.c:5818 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State Change CS_NEW -> CS_INIT<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_INIT<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State INIT<br>2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:86 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SOFIA INIT<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:126 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State Change CS_INIT -> CS_ROUTING<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State INIT going to sleep<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_ROUTING<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:2003 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Callstate Change DOWN -> RINGING<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State ROUTING<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:149 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SOFIA ROUTING<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:117 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Standard ROUTING<br>
2013-03-12 13:56:51.801956 [INFO] mod_dialplan_xml.c:557 Processing Parsa <401>->403 in context context_1<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->conditioning_callerid] continue=true<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (PASS) [conditioning_callerid] ${internal_caller_id_number}(401) =~ /^.+$/ break=on-false<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(effective_caller_id_name=${internal_caller_id_name})<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(effective_caller_id_number=${internal_caller_id_number})<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->conditioning_callrecord] continue=true<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Absolute Condition [conditioning_callrecord]<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_TITLE=Recording ${destination_number} ${caller_id_number} ${strftime(%Y-%m-%d %H:%M)})<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_COPYRIGHT=(c) 2010 VoIP, Inc.)<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_SOFTWARE=blue.box)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_ARTIST=2600hz)<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_COMMENT=Automatically recorded via FreeSWITCH with blue.box)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_DATE=${strftime(%Y-%m-%d %H:%M)})<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RECORD_STEREO=true)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->postroute_global] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Absolute Condition [postroute_global]<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action hash(insert/${domain_name}-last_dial/global/${uuid})<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->preanswer_callrecord_outbound] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [preanswer_callrecord_outbound] ${callrecord_outbound}() =~ /^.+$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_17] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_17] destination_number(403) =~ /^334$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_18] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_18] destination_number(403) =~ /^496$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_23] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_23] destination_number(403) =~ /^4101$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_13] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_13] destination_number(403) =~ /^288|223$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_25] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_25] destination_number(403) =~ /^422$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_27] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_27] destination_number(403) =~ /^0[^0][0-9]{9}$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_28] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_28] destination_number(403) =~ /^401$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_29] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_29] destination_number(403) =~ /^402$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_30] continue=true<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (PASS) [main_number_30] destination_number(403) =~ /^403$/ break=on-false<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(call_timeout=30)<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(ringback=local_stream://moh)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action set(transfer_ringback=local_stream://moh)<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action export(sip_callee_id_name=m.emami)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action export(sip_callee_id_number=403)<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action bridge(user/<a href="mailto:403@192.168.54.69">403@192.168.54.69</a>)<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Action hangup()<br>Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> parsing [context_1->main_number_31] continue=true<br>
Dialplan: sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Regex (FAIL) [main_number_31] destination_number(403) =~ /^497$/ break=on-false<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:167 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State Change CS_ROUTING -> CS_EXECUTE<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State ROUTING going to sleep<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_EXECUTE<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:477 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State EXECUTE<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:242 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SOFIA EXECUTE<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:209 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Standard EXECUTE<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(effective_caller_id_name=Parsa Moshrefi)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [effective_caller_id_name]=[Parsa Moshrefi]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(effective_caller_id_number=401)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [effective_caller_id_number]=[401]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_TITLE=Recording 403 401 2013-03-12 13:56)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_TITLE]=[Recording 403 401 2013-03-12 13:56]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_COPYRIGHT=(c) 2010 VoIP, Inc.)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_COPYRIGHT]=[(c) 2010 VoIP, Inc.]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_SOFTWARE=blue.box)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_SOFTWARE]=[blue.box]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_ARTIST=2600hz)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_ARTIST]=[2600hz]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_COMMENT=Automatically recorded via FreeSWITCH with blue.box)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_COMMENT]=[Automatically recorded via FreeSWITCH with blue.box]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_DATE=2013-03-12 13:56)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_DATE]=[2013-03-12 13:56]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RECORD_STEREO=true)<br>2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RECORD_STEREO]=[true]<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> hash(insert/192.168.54.69-spymap/401/5ab2118c-8aff-11e2-bd72-4f1eb6844087)<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> hash(insert/192.168.54.69-last_dial/401/403)<br>
EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> hash(insert/192.168.54.69-last_dial/global/5ab2118c-8aff-11e2-bd72-4f1eb6844087)<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(RFC2822_DATE=Tue, 12 Mar 2013 13:56:51 )<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [RFC2822_DATE]=[Tue, 12 Mar 2013 13:56:51 ]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(call_timeout=30)<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [call_timeout]=[30]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(ringback=local_stream://moh)<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [ringback]=[local_stream://moh]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> set(transfer_ringback=local_stream://moh)<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_dptools.c:1344 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SET [transfer_ringback]=[local_stream://moh]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> export(sip_callee_id_name=m.emami)<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_callee_id_name]=[m.emami]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> export(sip_callee_id_number=403)<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_callee_id_number]=[403]<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> bridge(user/<a href="mailto:403@192.168.54.69">403@192.168.54.69</a>)<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1089 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> EXPORTING[export_vars] [sip_callee_id_name]=[m.emami] to event<br>2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1089 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> EXPORTING[export_vars] [sip_callee_id_number]=[403] to event<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables<br>2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1089 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> EXPORTING[export_vars] [sip_callee_id_name]=[m.emami] to event<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:1089 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> EXPORTING[export_vars] [sip_callee_id_number]=[403] to event<br>2013-03-12 13:56:51.801956 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[<a href="mailto:403@192.168.54.69">403@192.168.54.69</a>]<br>2013-03-12 13:56:51.801956 [NOTICE] switch_channel.c:968 New Channel sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [5abc3f36-8aff-11e2-bd8e-4f1eb6844087]<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:4961 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State Change CS_NEW -> CS_INIT<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_INIT<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State INIT<br>
2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:86 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> SOFIA INIT<br>2013-03-12 13:56:51.801956 [DEBUG] sofia_glue.c:2647 Local SDP:<br>v=0<br>
o=FreeSWITCH 1363059707 1363059708 IN IP4 192.168.54.69<br>s=FreeSWITCH<br>c=IN IP4 192.168.54.69<br>t=0 0<br>m=audio 24304 RTP/AVP 8 9 0 3 101 13<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-16<br>a=ptime:20<br>
a=sendrecv<br>
<br>2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:126 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State Change CS_INIT -> CS_ROUTING<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State INIT going to sleep<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:975 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_ROUTING<br>2013-03-12 13:56:51.801956 [DEBUG] switch_channel.c:2003 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Callstate Change DOWN -> RINGING<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State ROUTING<br>2013-03-12 13:56:51.801956 [DEBUG] mod_sofia.c:149 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> SOFIA ROUTING<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State ROUTING going to sleep<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_CONSUME_MEDIA<br>
2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:489 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State CONSUME_MEDIA<br>2013-03-12 13:56:51.801956 [DEBUG] switch_core_state_machine.c:489 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State CONSUME_MEDIA going to sleep<br>
2013-03-12 13:56:51.801956 [DEBUG] sofia.c:5574 Channel sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> entering state [calling][0]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_channel.c:2994 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Callstate Change RINGING -> HANGUP<br>
2013-03-12 13:57:21.001969 [NOTICE] switch_ivr_originate.c:3351 Hangup sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [CS_CONSUME_MEDIA] [NO_ANSWER]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_HANGUP<br>
2013-03-12 13:57:21.001969 [DEBUG] switch_channel.c:3017 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [KILL]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>
2013-03-12 13:57:21.001969 [DEBUG] switch_core_state_machine.c:667 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State HANGUP<br>2013-03-12 13:57:21.001969 [DEBUG] mod_sofia.c:503 Channel sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> hanging up, cause: NO_ANSWER<br>
2013-03-12 13:57:21.001969 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [user] cause: [NO_ANSWER]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 19 [NO_ANSWER]<br>
2013-03-12 13:57:21.001969 [INFO] mod_dptools.c:3055 Originate Failed. Cause: NO_ANSWER<br>EXECUTE sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> hangup()<br>2013-03-12 13:57:21.001969 [DEBUG] switch_channel.c:2994 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Callstate Change RINGING -> HANGUP<br>
2013-03-12 13:57:21.001969 [NOTICE] mod_dptools.c:1150 Hangup sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [CS_EXECUTE] [NORMAL_CLEARING]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_channel.c:3017 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [KILL]<br>
2013-03-12 13:57:21.001969 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:57:21.001969 [DEBUG] switch_core_session.c:2689 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<br>
2013-03-12 13:57:21.001969 [DEBUG] switch_core_state_machine.c:477 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State EXECUTE going to sleep<br>2013-03-12 13:57:21.001969 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_HANGUP<br>
2013-03-12 13:57:21.001969 [DEBUG] switch_core_state_machine.c:667 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State HANGUP<br>2013-03-12 13:57:21.001969 [DEBUG] mod_sofia.c:503 Channel sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> hanging up, cause: NORMAL_CLEARING<br>
2013-03-12 13:57:21.021986 [DEBUG] mod_sofia.c:562 Sending CANCEL to sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a><br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:48 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> Standard HANGUP, cause: NO_ANSWER<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:667 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State HANGUP going to sleep<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State Change CS_HANGUP -> CS_REPORTING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_REPORTING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:749 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State REPORTING<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:92 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> Standard REPORTING, cause: NO_ANSWER<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:749 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State REPORTING going to sleep<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:440 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State Change CS_REPORTING -> CS_DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [BREAK]<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1499 Session 48 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Locked, Waiting on external entities<br>
2013-03-12 13:57:21.021986 [NOTICE] switch_core_session.c:1517 Session 48 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Ended<br>2013-03-12 13:57:21.021986 [NOTICE] switch_core_session.c:1521 Close Channel sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> [CS_DESTROY]<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:556 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Callstate Change HANGUP -> DOWN<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:559 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) Running State Change CS_DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:569 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State DESTROY<br>2013-03-12 13:57:21.021986 [DEBUG] mod_sofia.c:396 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> SOFIA DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:99 sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a> Standard DESTROY<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:569 (sofia/sipinterface_6/<a href="mailto:sip%3A403@192.168.1.115">sip:403@192.168.1.115</a>) State DESTROY going to sleep<br>
2013-03-12 13:57:21.021986 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 480<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:48 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Standard HANGUP, cause: NORMAL_CLEARING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:667 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State HANGUP going to sleep<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State Change CS_HANGUP -> CS_REPORTING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_REPORTING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:749 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State REPORTING<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:92 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Standard REPORTING, cause: NORMAL_CLEARING<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:749 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State REPORTING going to sleep<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:440 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State Change CS_REPORTING -> CS_DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1291 Send signal sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [BREAK]<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_session.c:1499 Session 47 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Locked, Waiting on external entities<br>
2013-03-12 13:57:21.021986 [NOTICE] switch_core_session.c:1517 Session 47 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Ended<br>2013-03-12 13:57:21.021986 [NOTICE] switch_core_session.c:1521 Close Channel sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> [CS_DESTROY]<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:556 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Callstate Change HANGUP -> DOWN<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:559 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) Running State Change CS_DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:569 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State DESTROY<br>2013-03-12 13:57:21.021986 [DEBUG] mod_sofia.c:396 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> SOFIA DESTROY<br>
2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:99 sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a> Standard DESTROY<br>2013-03-12 13:57:21.021986 [DEBUG] switch_core_state_machine.c:569 (sofia/sipinterface_6/<a href="mailto:401@192.168.54.69">401@192.168.54.69</a>) State DESTROY going to sleep<br>
<br>