Okay, a few things. First, let's use pastebin.freeswitch.org since there is so much information. Second, I recommend turning on sofia sip trace:
sofia global siptrace on

Make another test call and capture the output. Let's see what the other side sends leading up to the no route message.
-MC

On Thu, Oct 27, 2011 at 6:45 AM, Miha Zoubek <miha@softnet.si> wrote:
This log is from cli:

[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1164]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 136
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1164
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.325859 [DEBUG] switch_core_session.c:1164 Session 8 (sofia/external/051357952) Locked, Waiting on external entities
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [106]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [5]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_thread]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1182]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 106
Log-Level: 5
Text-Channel: 3
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1182
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.325859 [NOTICE] switch_core_session.c:1182 Session 8 (sofia/external/051357952) Ended
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [115]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [5]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_thread]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1184]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 115
Log-Level: 5
Text-Channel: 0
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1184
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.325859 [NOTICE] switch_core_session.c:1184 Close Channel sofia/external/051357952 [CS_DESTROY]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [126]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [428]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 126
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 428
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.328931 [DEBUG] switch_core_state_machine.c:428 (sofia/external/051357952) Running State Change CS_DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [108]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [439]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 108
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 439
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.328931 [DEBUG] switch_core_state_machine.c:439 (sofia/external/051357952) State DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [90]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [mod_sofia.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [sofia_on_destroy]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [341]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 90
Log-Level: 7
Text-Channel: 3
Log-File: mod_sofia.c
Log-Func: sofia_on_destroy
Log-Line: 341
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.328931 [DEBUG] mod_sofia.c:341 sofia/external/051357952 SOFIA DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [108]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_standard_on_destroy]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [60]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 108
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_standard_on_destroy
Log-Line: 60
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.328931 [DEBUG] switch_core_state_machine.c:60 sofia/external/051357952 Standard DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [123]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [439]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [9240aaac-7466-4a4d-aded-e74998dfeb01]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 123
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 439
User-Data: 9240aaac-7466-4a4d-aded-e74998dfeb01


2011-10-27 15:45:39.328931 [DEBUG] switch_core_state_machine.c:439 (sofia/external/051357952) State DESTROY going to sleep
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [82]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [mod_sofia.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [sofia_on_hangup]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [476]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 82
Log-Level: 7
Text-Channel: 3
Log-File: mod_sofia.c
Log-Func: sofia_on_hangup
Log-Line: 476
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.355813 [DEBUG] mod_sofia.c:476 Responding to INVITE with: 404
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [144]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_standard_on_hangup]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [46]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 144
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_standard_on_hangup
Log-Line: 46
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1030@172.31.1.192 Standard HANGUP, cause: NO_ROUTE_DESTINATION
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [130]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_hangup_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [499]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 130
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_hangup_state
Log-Line: 499
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1030@172.31.1.192) State HANGUP going to sleep
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [141]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_run]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [333]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 141
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_run
Log-Line: 333
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1030@172.31.1.192) State Change CS_HANGUP -> CS_REPORTING
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [115]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_signal_state_change]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1021]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 115
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_session.c
Log-Func: switch_core_session_signal_state_change
Log-Line: 1021
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [136]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_run]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [314]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 136
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_run
Log-Line: 314
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1030@172.31.1.192) Running State Change CS_REPORTING
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [118]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_reporting_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [590]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 118
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_reporting_state
Log-Line: 590
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1030@172.31.1.192) State REPORTING
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [147]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_standard_on_reporting]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [53]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 147
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_standard_on_reporting
Log-Line: 53
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1030@172.31.1.192 Standard REPORTING, cause: NO_ROUTE_DESTINATION
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [133]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_reporting_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [590]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 133
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_reporting_state
Log-Line: 590
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1030@172.31.1.192) State REPORTING going to sleep
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [142]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_run]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [327]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 142
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_run
Log-Line: 327
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/1030@172.31.1.192) State Change CS_REPORTING -> CS_DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [115]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_signal_state_change]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1021]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 115
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_session.c
Log-Func: switch_core_session_signal_state_change
Log-Line: 1021
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [144]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_thread]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1164]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 144
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1164
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_session.c:1164 Session 7 (sofia/internal/1030@172.31.1.192) Locked, Waiting on external entities
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [114]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [5]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_thread]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1182]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 114
Log-Level: 5
Text-Channel: 3
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1182
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [NOTICE] switch_core_session.c:1182 Session 7 (sofia/internal/1030@172.31.1.192) Ended
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [123]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [5]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_session.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_thread]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [1184]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 123
Log-Level: 5
Text-Channel: 0
Log-File: switch_core_session.c
Log-Func: switch_core_session_thread
Log-Line: 1184
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1030@172.31.1.192 [CS_DESTROY]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [134]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [0]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [428]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 134
Log-Level: 7
Text-Channel: 0
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 428
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:428 (sofia/internal/1030@172.31.1.192) Running State Change CS_DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [116]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [439]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 116
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 439
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1030@172.31.1.192) State DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [98]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [mod_sofia.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [sofia_on_destroy]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [341]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 98
Log-Level: 7
Text-Channel: 3
Log-File: mod_sofia.c
Log-Func: sofia_on_destroy
Log-Line: 341
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] mod_sofia.c:341 sofia/internal/1030@172.31.1.192 SOFIA DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [116]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_standard_on_destroy]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [60]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 116
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_standard_on_destroy
Log-Line: 60
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1030@172.31.1.192 Standard DESTROY
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Type] = [log/data]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Content-Length] = [131]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Level] = [7]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Text-Channel] = [3]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-File] = [switch_core_state_machine.c]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Func] = [switch_core_session_destroy_state]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [Log-Line] = [439]
[DEBUG] libs/esl/src/esl.c:918 esl_recv_event() RECV HEADER [User-Data] = [962db612-d472-4f8b-88f9-5349f0034660]
[DEBUG] libs/esl/src/esl.c:1037 esl_recv_event() RECV MESSAGE
Event-Name: SOCKET_DATA
Content-Type: log/data
Content-Length: 131
Log-Level: 7
Text-Channel: 3
Log-File: switch_core_state_machine.c
Log-Func: switch_core_session_destroy_state
Log-Line: 439
User-Data: 962db612-d472-4f8b-88f9-5349f0034660


2011-10-27 15:45:39.357844 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1030@172.31.1.192) State DESTROY going to sleep



On 10/27/2011 2:57 PM, Miha Zoubek wrote:
Hi,

path of my dialplan is: /usr/local/freeswitch/conf/dialplan/default/test.xml



I rename IP800 for innovaphone.

Here is my debugging log:

Dialplan: sofia/internal/1030@172.31.1.192 Regex (FAIL) [hold_music] destination_number(9051357952) =~ /^9999$/ break=on-false
Dialplan: sofia/internal/1030@172.31.1.192 parsing [default->Dial Out Custom Gateway] continue=false
Dialplan: sofia/internal/1030@172.31.1.192 Regex (PASS) [Dial Out Custom Gateway] destination_number(9051357952) =~ /^9(\d{9})$/ break=on-false
Dialplan: sofia/internal/1030@172.31.1.192 Action bridge(sofia/gateway/innovaphone/051357952)
2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/1030@172.31.1.192) State Change CS_ROUTING -> CS_EXECUTE
2011-10-27 14:54:58.927692 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1030@172.31.1.192) State ROUTING going to sleep
2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1030@172.31.1.192) Running State Change CS_EXECUTE
2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1030@172.31.1.192) State EXECUTE
2011-10-27 14:54:58.927692 [DEBUG] mod_sofia.c:226 sofia/internal/1030@172.31.1.192 SOFIA EXECUTE
2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1030@172.31.1.192 Standard EXECUTE
EXECUTE sofia/internal/1030@172.31.1.192 set(open=true)
2011-10-27 14:54:58.927692 [DEBUG] mod_dptools.c:816 sofia/internal/1030@172.31.1.192 SET [open]=[true]
EXECUTE sofia/internal/1030@172.31.1.192 hash(insert/172.31.1.192-spymap/1030/147c8578-500d-4d91-bad5-9391c4400934)
EXECUTE sofia/internal/1030@172.31.1.192 hash(insert/172.31.1.192-last_dial/1030/9051357952)
EXECUTE sofia/internal/1030@172.31.1.192 hash(insert/172.31.1.192-last_dial/global/147c8578-500d-4d91-bad5-9391c4400934)
EXECUTE sofia/internal/1030@172.31.1.192 bridge(sofia/gateway/innovaphone/051357952)
2011-10-27 14:54:58.931685 [NOTICE] switch_channel.c:669 New Channel sofia/external/051357952 [c4a81269-04e7-42db-aaf8-ecf528b74d68]
2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:3384 (sofia/external/051357952) State Change CS_NEW -> CS_INIT
2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 (sofia/external/051357952) Running State Change CS_INIT
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:338 (sofia/external/051357952) State INIT
2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:83 sofia/external/051357952 SOFIA INIT
2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:117 (sofia/external/051357952) State Change CS_INIT -> CS_ROUTING
2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:58.933717 [DEBUG] sofia.c:4153 Channel sofia/external/051357952 entering state [calling][0]
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:338 (sofia/external/051357952) State INIT going to sleep
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 (sofia/external/051357952) Running State Change CS_ROUTING
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:341 (sofia/external/051357952) State ROUTING
2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:140 sofia/external/051357952 SOFIA ROUTING
2011-10-27 14:54:58.933717 [DEBUG] switch_ivr_originate.c:66 (sofia/external/051357952) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:341 (sofia/external/051357952) State ROUTING going to sleep
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 (sofia/external/051357952) Running State Change CS_CONSUME_MEDIA
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:360 (sofia/external/051357952) State CONSUME_MEDIA
2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:360 (sofia/external/051357952) State CONSUME_MEDIA going to sleep
2011-10-27 14:54:58.937691 [DEBUG] sofia.c:4153 Channel sofia/external/051357952 entering state [calling][0]
2011-10-27 14:54:59.062721 [DEBUG] sofia.c:4153 Channel sofia/external/051357952 entering state [terminated][404]
2011-10-27 14:54:59.062721 [NOTICE] sofia.c:4789 Hangup sofia/external/051357952 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2011-10-27 14:54:59.062721 [DEBUG] switch_channel.c:2102 Send signal sofia/external/051357952 [KILL]
2011-10-27 14:54:59.062721 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:59.062721 [DEBUG] switch_core_state_machine.c:314 (sofia/external/051357952) Running State Change CS_HANGUP
2011-10-27 14:54:59.064679 [DEBUG] switch_ivr_originate.c:3228 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
2011-10-27 14:54:59.064679 [INFO] mod_dptools.c:2355 Originate Failed.  Cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.064679 [NOTICE] mod_dptools.c:2418 Hangup sofia/internal/1030@172.31.1.192 [CS_EXECUTE] [NO_ROUTE_DESTINATION]
2011-10-27 14:54:59.064679 [DEBUG] switch_channel.c:2102 Send signal sofia/internal/1030@172.31.1.192 [KILL]
2011-10-27 14:54:59.064679 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1030@172.31.1.192) State EXECUTE going to sleep
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1030@172.31.1.192) Running State Change CS_HANGUP
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1030@172.31.1.192) State HANGUP
2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:408 sofia/internal/1030@172.31.1.192 Overriding SIP cause 404 with 404 from the other leg
2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:414 Channel sofia/internal/1030@172.31.1.192 hanging up, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:499 (sofia/external/051357952) State HANGUP
2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:408 sofia/external/051357952 Overriding SIP cause 404 with 404 from the other leg
2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:414 Channel sofia/external/051357952 hanging up, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:46 sofia/external/051357952 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:499 (sofia/external/051357952) State HANGUP going to sleep
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:333 (sofia/external/051357952) State Change CS_HANGUP -> CS_REPORTING
2011-10-27 14:54:59.064679 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:314 (sofia/external/051357952) Running State Change CS_REPORTING
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:590 (sofia/external/051357952) State REPORTING
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:53 sofia/external/051357952 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:590 (sofia/external/051357952) State REPORTING going to sleep
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:327 (sofia/external/051357952) State Change CS_REPORTING -> CS_DESTROY
2011-10-27 14:54:59.064679 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/051357952 [BREAK]
2011-10-27 14:54:59.064679 [DEBUG] switch_core_session.c:1164 Session 4 (sofia/external/051357952) Locked, Waiting on external entities
2011-10-27 14:54:59.064679 [NOTICE] switch_core_session.c:1182 Session 4 (sofia/external/051357952) Ended
2011-10-27 14:54:59.064679 [NOTICE] switch_core_session.c:1184 Close Channel sofia/external/051357952 [CS_DESTROY]
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:428 (sofia/external/051357952) Running State Change CS_DESTROY
2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:439 (sofia/external/051357952) State DESTROY
2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:341 sofia/external/051357952 SOFIA DESTROY
2011-10-27 14:54:59.066681 [DEBUG] switch_core_state_machine.c:60 sofia/external/051357952 Standard DESTROY
2011-10-27 14:54:59.066681 [DEBUG] switch_core_state_machine.c:439 (sofia/external/051357952) State DESTROY going to sleep
2011-10-27 14:54:59.108644 [DEBUG] mod_sofia.c:476 Responding to INVITE with: 404
2011-10-27 14:54:59.108644 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1030@172.31.1.192 Standard HANGUP, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.108644 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1030@172.31.1.192) State HANGUP going to sleep
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1030@172.31.1.192) State Change CS_HANGUP -> CS_REPORTING
2011-10-27 14:54:59.110650 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1030@172.31.1.192) Running State Change CS_REPORTING
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1030@172.31.1.192) State REPORTING
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1030@172.31.1.192 Standard REPORTING, cause: NO_ROUTE_DESTINATION
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:590 (sofia/internal/1030@172.31.1.192) State REPORTING going to sleep
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/1030@172.31.1.192) State Change CS_REPORTING -> CS_DESTROY
2011-10-27 14:54:59.110650 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1030@172.31.1.192 [BREAK]
2011-10-27 14:54:59.110650 [DEBUG] switch_core_session.c:1164 Session 3 (sofia/internal/1030@172.31.1.192) Locked, Waiting on external entities
2011-10-27 14:54:59.110650 [NOTICE] switch_core_session.c:1182 Session 3 (sofia/internal/1030@172.31.1.192) Ended
2011-10-27 14:54:59.110650 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1030@172.31.1.192 [CS_DESTROY]
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:428 (sofia/internal/1030@172.31.1.192) Running State Change CS_DESTROY
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1030@172.31.1.192) State DESTROY
2011-10-27 14:54:59.110650 [DEBUG] mod_sofia.c:341 sofia/internal/1030@172.31.1.192 SOFIA DESTROY
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1030@172.31.1.192 Standard DESTROY
2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1030@172.31.1.192) State DESTROY going to slee


BR,
Miha

On 10/27/2011 2:15 PM, Nandy Dagondon wrote:
what is the full path/filename of your dialplan? 
your debug level is not detailed enough.  set the log level to 7.
$fs_cli -l 7


On Thu, Oct 27, 2011 at 6:07 PM, Miha Zoubek <miha@softnet.si> wrote:
Hi,

I have dialed 9051357952. IP800 is our GW. I created on it one SIP account and connect freeswitch with it.

BR,
Miha


On 10/27/2011 11:19 AM, Nandy Dagondon wrote:
hi,

what is IP800? is this a FXO gateway? or a SIP provider?

u hv to dial 9051357952 to match the dialplan.

-nandy

On Wed, Oct 26, 2011 at 9:34 PM, Miha Zoubek <miha@softnet.si> wrote:
Hi,

I am new Freeswitch user. I have read freeswitch book. I registered first few phones and make few inbound calls.
I am trying to get outgoing calls working but having no luck.
I have configured my gw and set up calling plan. When I try to dial number 051357952 I get this:

reeswitch@localhost.localdomain> 2011-10-26 15:27:25.919562 [NOTICE] switch_channel.c:669 New Channel sofia/internal/1030@172.31.1.157 [f2132149-dc40-4601-bd34-c22e5b06b696]
2011-10-26 15:27:25.955550 [INFO] mod_dialplan_xml.c:418 Processing 1030->9051357952 in context default
2011-10-26 15:27:25.960548 [NOTICE] switch_channel.c:669 New Channel sofia/external/051357952 [5ccfd0ae-1bcc-4f19-9ca1-e19f8808e6a4]
2011-10-26 15:27:26.095518 [NOTICE] sofia.c:4789 Hangup sofia/external/051357952 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2011-10-26 15:27:26.098534 [INFO] mod_dptools.c:2355 Originate Failed.  Cause: NO_ROUTE_DESTINATION
2011-10-26 15:27:26.098534 [NOTICE] mod_dptools.c:2418 Hangup sofia/internal/1030@172.31.1.157 [CS_EXECUTE] [NO_ROUTE_DESTINATION]
2011-10-26 15:27:26.098534 [NOTICE] switch_core_session.c:1182 Session 49 (sofia/external/051357952) Ended
2011-10-26 15:27:26.098534 [NOTICE] switch_core_session.c:1184 Close Channel sofia/external/051357952 [CS_DESTROY]
2011-10-26 15:27:26.140506 [NOTICE] switch_core_session.c:1182 Session 48 (sofia/internal/1030@172.31.1.157) Ended
2011-10-26 15:27:26.140506 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1030@172.31.1.157 [CS_DESTROY]

GW:

<include>
  <gateway name="IP800">
    <!-- Your username, which is not your DID -->
    <param name="username" value="Demo2"/>
    <param name="password" value="1234"/>
    <param name="register" value="true"/>
    <param name="realm" value="172.31.1.200"/>
 </gateway>
</include>

This is my dial plan:

<include>
        <extension name="Dial Out Custom Gateway">
                <condition field="destination_number" expression="^9(\d{9})$">
        <action application="bridge" data="sofia/gateway/IP800/$1"/>
                </condition>
        </extension>
</include>


sofia status

                     Name          Type                                       Data      State
=================================================================================================
                 external       profile            sip:mod_sofia@172.31.1.157:5080      RUNNING (0)
    external::example.com       gateway                    sip:joeuser@example.com      NOREG
          external::IP800       gateway                     sip:Demo2@172.31.1.200      REGED
                 internal       profile            sip:mod_sofia@172.31.1.157:5060      RUNNING (0)
             172.31.1.157         alias                                   internal      ALIASED
=================================================================================================
2 profiles 1 alias


Please help me why I am getting not route to destination.

Thank you very much for all your help!

BR,
Miha


FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org




FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org




FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org




FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org