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.192hash(insert/172.31.1.192-spymap/1030/147c8578-500d-4d91-bad5-9391c4400934) EXECUTE sofia/internal/1030@172.31.1.192hash(insert/172.31.1.192-last_dial/1030/9051357952) EXECUTE sofia/internal/1030@172.31.1.192hash(insert/172.31.1.192-last_dial/global/147c8578-500d-4d91-bad5-9391c4400934) EXECUTE sofia/internal/1030@172.31.1.192bridge(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 listFreeSWITCH-users@lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.frees...
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 listFreeSWITCH-users@lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.frees...
FreeSWITCH-users mailing listFreeSWITCH-users@lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.frees...
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