[Freeswitch-users] freeswitch as SBC and kamailio - no route

Wed Aug 26 09:37:16 PDT 2009

 Hello

I followed the tutorial
http://wiki.freeswitch.org/wiki/SBC_Setup

I have following problem when I dial 1000 Kamalio reports 302, but freeswitch does not route

Where to look for problems?

Here is the debug:

2009-08-26 20:21:42.332154 [INFO] mod_dialplan_xml.c:315 Processing 1001->1000 in context default
Dialplan: sofia/internal/1001 at 10.10.10.10 parsing [default->LOOKUP_ROUTE] continue=false
Dialplan: sofia/internal/1001 at 10.10.10.10 Regex (PASS) [LOOKUP_ROUTE] destination_number(1000) =~ /(\d+)$/ break=on-false
Dialplan: sofia/internal/1001 at 10.10.10.10 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/1001 at 10.10.10.10 Action set(continue_on_fail=true)
Dialplan: sofia/internal/1001 at 10.10.10.10 Action export(sip_h_X-ROUTE=LOOKUP)
Dialplan: sofia/internal/1001 at 10.10.10.10 Action bridge(sofia/internal/${destination_number}@127.0.0.1:5062)
Dialplan: sofia/internal/1001 at 10.10.10.10 Action set(ROUTE_GW=${sip_redirect_contact_user_0})
Dialplan: sofia/internal/1001 at 10.10.10.10 Action set(AREA=${sip_redirect_contact_user_0})
Dialplan: sofia/internal/1001 at 10.10.10.10 Action transfer(${destination_number} XML ROUTING)
2009-08-26 20:21:42.334579 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001 at 10.10.10.10) State Change CS_ROUTING -> CS_EXECUTE
2009-08-26 20:21:42.334579 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1001 at 10.10.10.10 [BREAK]
2009-08-26 20:21:42.334579 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1001 at 10.10.10.10) State ROUTING going to sleep
2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1001 at 10.10.10.10) Running State Change CS_EXECUTE
2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1001 at 10.10.10.10) State EXECUTE
2009-08-26 20:21:42.335292 [DEBUG] mod_sofia.c:173 sofia/internal/1001 at 10.10.10.10 SOFIA EXECUTE
2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001 at 10.10.10.10 Standard EXECUTE
EXECUTE sofia/internal/1001 at 10.10.10.10 set(hangup_after_bridge=true)
2009-08-26 20:21:42.336509 [DEBUG] mod_dptools.c:748 sofia/internal/1001 at 10.10.10.10 SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1001 at 10.10.10.10 set(continue_on_fail=true)
2009-08-26 20:21:42.337353 [DEBUG] mod_dptools.c:748 sofia/internal/1001 at 10.10.10.10 SET [continue_on_fail]=[true]
EXECUTE sofia/internal/1001 at 10.10.10.10 export(sip_h_X-ROUTE=LOOKUP)
2009-08-26 20:21:42.338352 [DEBUG] mod_dptools.c:886 EXPORT [sip_h_X-ROUTE]=[LOOKUP]
EXECUTE sofia/internal/1001 at 10.10.10.10 bridge(sofia/internal/1000 at 127.0.0.1:5062)
2009-08-26 20:21:42.339309 [NOTICE] switch_channel.c:602 New Channel sofia/internal/1000 at 127.0.0.1:5062 [8a588e6a-925c-11de-85dd-15dc0a06983f]
2009-08-26 20:21:42.339309 [DEBUG] mod_sofia.c:2811 (sofia/internal/1000 at 127.0.0.1:5062) State Change CS_NEW -> CS_INIT
2009-08-26 20:21:42.340376 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000 at 127.0.0.1:5062 [BREAK]
2009-08-26 20:21:42.341175 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000 at 127.0.0.1:5062) Running State Change CS_INIT
2009-08-26 20:21:42.341175 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/1000 at 127.0.0.1:5062) State INIT
2009-08-26 20:21:42.341175 [DEBUG] mod_sofia.c:83 sofia/internal/1000 at 127.0.0.1:5062 SOFIA INIT
2009-08-26 20:21:42.344378 [DEBUG] mod_sofia.c:111 (sofia/internal/1000 at 127.0.0.1:5062) State Change CS_INIT -> CS_ROUTING
2009-08-26 20:21:42.344378 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000 at 127.0.0.1:5062 [BREAK]
2009-08-26 20:21:42.344378 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/1000 at 127.0.0.1:5062) State INIT going to sleep
2009-08-26 20:21:42.344378 [DEBUG] sofia.c:3289 Channel sofia/internal/1000 at 127.0.0.1:5062 entering state [calling][0]
2009-08-26 20:21:42.344378 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000 at 127.0.0.1:5062) Running State Change CS_ROUTING
2009-08-26 20:21:42.345314 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1000 at 127.0.0.1:5062) State ROUTING
2009-08-26 20:21:42.345314 [DEBUG] mod_sofia.c:130 sofia/internal/1000 at 127.0.0.1:5062 SOFIA ROUTING
2009-08-26 20:21:42.345314 [DEBUG] switch_ivr_originate.c:63 (sofia/internal/1000 at 127.0.0.1:5062) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2009-08-26 20:21:42.345314 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000 at 127.0.0.1:5062 [BREAK]
2009-08-26 20:21:42.345314 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1000 at 127.0.0.1:5062) State ROUTING going to sleep
2009-08-26 20:21:42.346255 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000 at 127.0.0.1:5062) Running State Change CS_CONSUME_MEDIA
2009-08-26 20:21:42.346255 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/1000 at 127.0.0.1:5062) State CONSUME_MEDIA
2009-08-26 20:21:42.349173 [DEBUG] sofia.c:3289 Channel sofia/internal/1000 at 127.0.0.1:5062 entering state [calling][0]
2009-08-26 20:21:42.350242 [DEBUG] sofia.c:3289 Channel sofia/internal/1000 at 127.0.0.1:5062 entering state [terminated][503]
2009-08-26 20:21:42.350242 [NOTICE] sofia.c:3849 Hangup sofia/internal/1000 at 127.0.0.1:5062 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2009-08-26 20:21:42.351172 [DEBUG] switch_ivr_originate.c:2138 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2009-08-26 20:21:42.351172 [INFO] mod_dptools.c:2093 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
2009-08-26 20:21:42.351172 [DEBUG] mod_dptools.c:2120 Continue on fail [true]:  Cause: NORMAL_TEMPORARY_FAILURE
EXECUTE sofia/internal/1001 at 10.10.10.10 set(ROUTE_GW=PEER-01)
2009-08-26 20:21:42.352234 [DEBUG] mod_dptools.c:748 sofia/internal/1001 at 10.10.10.10 SET [ROUTE_GW]=[PEER-01]
2009-08-26 20:21:42.352234 [DEBUG] switch_channel.c:1683 Send signal sofia/internal/1000 at 127.0.0.1:5062 [KILL]
2009-08-26 20:21:42.352234 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000 at 127.0.0.1:5062 [BREAK]
EXECUTE sofia/internal/1001 at 10.10.10.10 set(AREA=PEER-01)
2009-08-26 20:21:42.353179 [DEBUG] mod_dptools.c:748 sofia/internal/1001 at 10.10.10.10 SET [AREA]=[PEER-01]
EXECUTE sofia/internal/1001 at 10.10.10.10 transfer(1000 XML ROUTING)
2009-08-26 20:21:42.355174 [DEBUG] switch_ivr.c:1343 (sofia/internal/1001 at 10.10.10.10) State Change CS_EXECUTE -> CS_ROUTING
2009-08-26 20:21:42.355174 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1001 at 10.10.10.10 [BREAK]
2009-08-26 20:21:42.355174 [DEBUG] switch_ivr.c:1347 sofia/internal/1001 at 10.10.10.10 receive message [TRANSFER]
2009-08-26 20:21:42.355174 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/1001 at 10.10.10.10 [BREAK]
2009-08-26 20:21:42.355174 [NOTICE] switch_ivr.c:1349 Transfer sofia/internal/1001 at 10.10.10.10 to XML[1000 at ROUTING]
2009-08-26 20:21:42.356405 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1001 at 10.10.10.10) State EXECUTE going to sleep
2009-08-26 20:21:42.356405 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1001 at 10.10.10.10) Running State Change CS_ROUTING
2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/1000 at 127.0.0.1:5062) State CONSUME_MEDIA going to sleep
2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000 at 127.0.0.1:5062) Running State Change CS_HANGUP
2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1001 at 10.10.10.10) State ROUTING
2009-08-26 20:21:42.357172 [DEBUG] mod_sofia.c:130 sofia/internal/1001 at 10.10.10.10 SOFIA ROUTING
2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001 at 10.10.10.10 Standard ROUTING
2009-08-26 20:21:42.357172 [INFO] mod_dialplan_xml.c:315 Processing 1001->1000 in context ROUTING
Dialplan: sofia/internal/1001 at 10.10.10.10 parsing [ROUTING->PEER_01] continue=false
2009-08-26 20:21:42.358179 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/1000 at 127.0.0.1:5062) State HANGUP
Dialplan: sofia/internal/1001 at 10.10.10.10 Regex (FAIL) [PEER_01] ${sip_h_X-ROUTE}(LOOKUP) =~ /PEER_01/ break=on-false
2009-08-26 20:21:42.358179 [INFO] switch_core_state_machine.c:136 No Route, Aborting
2009-08-26 20:21:42.358179 [DEBUG] mod_sofia.c:306 sofia/internal/1000 at 127.0.0.1:5062 Overriding SIP cause 503 with 503 from the other leg
2009-08-26 20:21:42.359202 [NOTICE] switch_core_state_machine.c:137 Hangup sofia/internal/1001 at 10.10.10.10 [CS_ROUTING] [NO_ROUTE_DESTINATION]