[Freeswitch-users] Getting NO_ROUTE_DESTINATION

Miha Zoubek miha at softnet.si
Thu Oct 27 17:45:52 MSD 2011 

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.192 
> hash(insert/172.31.1.192-spymap/1030/147c8578-500d-4d91-bad5-9391c4400934)
> EXECUTE sofia/internal/1030 at 172.31.1.192 
> hash(insert/172.31.1.192-last_dial/1030/9051357952)
> EXECUTE sofia/internal/1030 at 172.31.1.192 
> hash(insert/172.31.1.192-last_dial/global/147c8578-500d-4d91-bad5-9391c4400934)
> EXECUTE sofia/internal/1030 at 172.31.1.192 
> bridge(sofia/gateway/innovaphone/051357952)
> 2011-10-27 14:54:58.931685 [NOTICE] switch_channel.c:669 New Channel 
> sofia/external/051357952 [c4a81269-04e7-42db-aaf8-ecf528b74d68]
> 2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:3384 
> (sofia/external/051357952) State Change CS_NEW -> CS_INIT
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send 
> signal sofia/external/051357952 [BREAK]
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 
> (sofia/external/051357952) Running State Change CS_INIT
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:338 
> (sofia/external/051357952) State INIT
> 2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:83 
> sofia/external/051357952 SOFIA INIT
> 2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:117 
> (sofia/external/051357952) State Change CS_INIT -> CS_ROUTING
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send 
> signal sofia/external/051357952 [BREAK]
> 2011-10-27 14:54:58.933717 [DEBUG] sofia.c:4153 Channel 
> sofia/external/051357952 entering state [calling][0]
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:338 
> (sofia/external/051357952) State INIT going to sleep
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 
> (sofia/external/051357952) Running State Change CS_ROUTING
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:341 
> (sofia/external/051357952) State ROUTING
> 2011-10-27 14:54:58.933717 [DEBUG] mod_sofia.c:140 
> sofia/external/051357952 SOFIA ROUTING
> 2011-10-27 14:54:58.933717 [DEBUG] switch_ivr_originate.c:66 
> (sofia/external/051357952) State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_session.c:1021 Send 
> signal sofia/external/051357952 [BREAK]
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:341 
> (sofia/external/051357952) State ROUTING going to sleep
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:314 
> (sofia/external/051357952) Running State Change CS_CONSUME_MEDIA
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:360 
> (sofia/external/051357952) State CONSUME_MEDIA
> 2011-10-27 14:54:58.933717 [DEBUG] switch_core_state_machine.c:360 
> (sofia/external/051357952) State CONSUME_MEDIA going to sleep
> 2011-10-27 14:54:58.937691 [DEBUG] sofia.c:4153 Channel 
> sofia/external/051357952 entering state [calling][0]
> 2011-10-27 14:54:59.062721 [DEBUG] sofia.c:4153 Channel 
> sofia/external/051357952 entering state [terminated][404]
> 2011-10-27 14:54:59.062721 [NOTICE] sofia.c:4789 Hangup 
> sofia/external/051357952 [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
> 2011-10-27 14:54:59.062721 [DEBUG] switch_channel.c:2102 Send signal 
> sofia/external/051357952 [KILL]
> 2011-10-27 14:54:59.062721 [DEBUG] switch_core_session.c:1021 Send 
> signal sofia/external/051357952 [BREAK]
> 2011-10-27 14:54:59.062721 [DEBUG] switch_core_state_machine.c:314 
> (sofia/external/051357952) Running State Change CS_HANGUP
> 2011-10-27 14:54:59.064679 [DEBUG] switch_ivr_originate.c:3228 
> Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
> 2011-10-27 14:54:59.064679 [INFO] mod_dptools.c:2355 Originate 
> Failed.  Cause: NO_ROUTE_DESTINATION
> 2011-10-27 14:54:59.064679 [NOTICE] mod_dptools.c:2418 Hangup 
> sofia/internal/1030 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 
>> <mailto:miha at softnet.si>> wrote:
>>
>>     Hi,
>>
>>     I have dialed 9051357952 <tel: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 <tel:9051357952> to match the dialplan.
>>>
>>>     -nandy
>>>
>>>     On Wed, Oct 26, 2011 at 9:34 PM, Miha Zoubek <miha at softnet.si
>>>     <mailto: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 <tel:2011-10-26>
>>>         15:27:25.919562 <tel:25.919562> [NOTICE]
>>>         switch_channel.c:669 New Channel
>>>         sofia/internal/1030 at 172.31.1.157 <mailto:1030 at 172.31.1.157>
>>>         [f2132149-dc40-4601-bd34-c22e5b06b696]
>>>         2011-10-26 <tel:2011-10-26> 15:27:25.955550 <tel:25.955550>
>>>         [INFO] mod_dialplan_xml.c:418 Processing 1030->9051357952
>>>         <tel:9051357952> in context default
>>>         2011-10-26 <tel:2011-10-26> 15:27:25.960548 <tel:25.960548>
>>>         [NOTICE] switch_channel.c:669 New Channel
>>>         sofia/external/051357952 [5ccfd0ae-1bcc-4f19-9ca1-e19f8808e6a4]
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.095518 <tel:26.095518>
>>>         [NOTICE] sofia.c:4789 Hangup sofia/external/051357952
>>>         [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.098534 <tel:26.098534>
>>>         [INFO] mod_dptools.c:2355 Originate Failed.  Cause:
>>>         NO_ROUTE_DESTINATION
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.098534 <tel:26.098534>
>>>         [NOTICE] mod_dptools.c:2418 Hangup
>>>         sofia/internal/1030 at 172.31.1.157 <mailto:1030 at 172.31.1.157>
>>>         [CS_EXECUTE] [NO_ROUTE_DESTINATION]
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.098534 <tel:26.098534>
>>>         [NOTICE] switch_core_session.c:1182 Session 49
>>>         (sofia/external/051357952) Ended
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.098534 <tel:26.098534>
>>>         [NOTICE] switch_core_session.c:1184 Close Channel
>>>         sofia/external/051357952 [CS_DESTROY]
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.140506 <tel:26.140506>
>>>         [NOTICE] switch_core_session.c:1182 Session 48
>>>         (sofia/internal/1030 at 172.31.1.157
>>>         <mailto:1030 at 172.31.1.157>) Ended
>>>         2011-10-26 <tel:2011-10-26> 15:27:26.140506 <tel:26.140506>
>>>         [NOTICE] switch_core_session.c:1184 Close Channel
>>>         sofia/internal/1030 at 172.31.1.157 <mailto: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 <http://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
>>>         <mailto: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 list
>>>     FreeSWITCH-users at lists.freeswitch.org  <mailto: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 list
>>     FreeSWITCH-users at lists.freeswitch.org
>>     <mailto: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 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 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/7aadf8a7/attachment-0001.html

Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list