[Freeswitch-users] Getting NO_ROUTE_DESTINATION
Michael Collins
msc at freeswitch.org
Thu Oct 27 19:18:47 MSD 2011
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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 172.31.1.192 Regex (FAIL) [hold_music]
> destination_number(9051357952) =~ /^9999$/ break=on-false
> Dialplan: sofia/internal/1030 at 172.31.1.192 parsing [default->Dial Out
> Custom Gateway] continue=false
> Dialplan: sofia/internal/1030 at 172.31.1.192 Regex (PASS) [Dial Out Custom
> Gateway] destination_number(9051357952) =~ /^9(\d{9})$/ break=on-false
> Dialplan: sofia/internal/1030 at 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 at 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 at 172.31.1.192 [BREAK]
> 2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:341 (
> sofia/internal/1030 at 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 at 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 at 172.31.1.192) State EXECUTE
> 2011-10-27 14:54:58.927692 [DEBUG] mod_sofia.c:226
> sofia/internal/1030 at 172.31.1.192 SOFIA EXECUTE
> 2011-10-27 14:54:58.927692 [DEBUG] switch_core_state_machine.c:157
> sofia/internal/1030 at 172.31.1.192 Standard EXECUTE
> EXECUTE sofia/internal/1030 at 172.31.1.192 set(open=true)
> 2011-10-27 14:54:58.927692 [DEBUG] mod_dptools.c:816
> sofia/internal/1030 at 172.31.1.192 SET [open]=[true]
> EXECUTE sofia/internal/1030 at 172.31.1.192hash(insert/172.31.1.192-spymap/1030/147c8578-500d-4d91-bad5-9391c4400934)
> EXECUTE sofia/internal/1030 at 172.31.1.192hash(insert/172.31.1.192-last_dial/1030/9051357952)
> EXECUTE sofia/internal/1030 at 172.31.1.192hash(insert/172.31.1.192-last_dial/global/147c8578-500d-4d91-bad5-9391c4400934)
> EXECUTE sofia/internal/1030 at 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 at 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 at 172.31.1.192 [KILL]
> 2011-10-27 14:54:59.064679 [DEBUG] switch_core_session.c:1021 Send signal
> sofia/internal/1030 at 172.31.1.192 [BREAK]
> 2011-10-27 14:54:59.064679 [DEBUG] switch_core_state_machine.c:348 (
> sofia/internal/1030 at 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 at 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 at 172.31.1.192) State HANGUP
> 2011-10-27 14:54:59.064679 [DEBUG] mod_sofia.c:408
> sofia/internal/1030 at 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 at 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 at 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 at 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 at 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 at 172.31.1.192 [BREAK]
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:314 (
> sofia/internal/1030 at 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 at 172.31.1.192) State REPORTING
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:53
> sofia/internal/1030 at 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 at 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 at 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 at 172.31.1.192 [BREAK]
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_session.c:1164 Session 3 (
> sofia/internal/1030 at 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 at 172.31.1.192) Ended
> 2011-10-27 14:54:59.110650 [NOTICE] switch_core_session.c:1184 Close
> Channel sofia/internal/1030 at 172.31.1.192 [CS_DESTROY]
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:428 (
> sofia/internal/1030 at 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 at 172.31.1.192) State DESTROY
> 2011-10-27 14:54:59.110650 [DEBUG] mod_sofia.c:341
> sofia/internal/1030 at 172.31.1.192 SOFIA DESTROY
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:60
> sofia/internal/1030 at 172.31.1.192 Standard DESTROY
> 2011-10-27 14:54:59.110650 [DEBUG] switch_core_state_machine.c:439 (
> sofia/internal/1030 at 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 at 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 at 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 at localhost.localdomain> 2011-10-26 15:27:25.919562 [NOTICE]
>>> switch_channel.c:669 New Channel sofia/internal/1030 at 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 at 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 at 172.31.1.157) Ended
>>> 2011-10-26 15:27:26.140506 [NOTICE] switch_core_session.c:1184 Close
>>> Channel sofia/internal/1030 at 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 at 172.31.1.157:5080 RUNNING (0)
>>> external::example.com gateway
>>> sip:joeuser at example.com NOREG
>>> external::IP800 gateway
>>> sip:Demo2 at 172.31.1.200 REGED
>>> internal profile
>>> sip:mod_sofia at 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 at 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 at lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.freeswitch.org
>>
>>
>>
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at 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 at lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.freeswitch.org
>
>
>
>
> FreeSWITCH-users mailing listFreeSWITCH-users at lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.freeswitch.org
>
>
>
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20111027/4b62b6e6/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list