SIP registration fails when using hostname in sip_profile ?
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/spymap/${caller_id_number}/) 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/spymap/ludovic/0) 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/last_dial/${caller_id_numbe) 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/last_dial/ludovi) 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/last_dial/global/${uuid}) 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/last_dial/global) 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute bridge(sofia/gateway/freephonie.net/0) 2009-03-16 18:29:42 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/external/0123456789 [08ee8f46-1250-11de-9e6d-07496a8df75d] 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:2029 sofia_outgoing_channel() sofia/external/0123456789 State Change CS_NEW -> CS_INIT 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK] 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_INIT 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:416 switch_core_session_run() (sofia/external/0123456789) State INIT 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:85 sofia_on_init() sofia/external/0123456789 SOFIA INIT 2009-03-16 18:29:42 [DEBUG] sofia_glue.c:488 sofia_glue_ext_address_lookup() Stun Success [89.226.241.9]:[18992] 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:112 sofia_on_init() sofia/external/0123456789 State Change CS_INIT -> CS_ROUTING 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK] 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:416 switch_core_session_run() (sofia/external/0123456789) State INIT going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_ROUTING 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:421 switch_core_session_run() (sofia/external/0123456789) State ROUTING 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:124 sofia_on_routing() sofia/external/0123456789 SOFIA ROUTING 2009-03-16 18:29:42 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() sofia/external/0123456789 State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK] 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:421 switch_core_session_run() (sofia/external/0123456789) State ROUTING going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_CONSUME_MEDIA 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:443 switch_core_session_run() (sofia/external/0123456789) State CONSUME_MEDIA 2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/external/0123456789 2009-03-16 18:29:42 [DEBUG] sofia.c:2177 sofia_handle_sip_i_state() Channel sofia/external/0123456789 entering state [calling] 2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_r_invite] status [503][DNS Error] session: sofia/external/0123456789 2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [503][DNS Error] session: sofia/external/0123456789 2009-03-16 18:29:42 [DEBUG] sofia.c:2177 sofia_handle_sip_i_state() Channel sofia/external/0123456789 entering state [terminated] 2009-03-16 18:29:42 [NOTICE] sofia.c:2598 sofia_handle_sip_i_state() Hangup sofia/external/0123456789 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2009-03-16 18:29:42 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/external/0123456789 [KILL] 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK] 2009-03-16 18:29:42 [DEBUG] switch_ivr_originate.c:1448 switch_ivr_originate() Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2009-03-16 18:29:42 [INFO] mod_dptools.c:1814 audio_bridge_function() Originate Failed. Cause: NORMAL_TEMPORARY_FAILURE 2009-03-16 18:29:42 [NOTICE] mod_dptools.c:1841 audio_bridge_function() Hangup sofia/internal/ludovic@192.168.1.1:5061 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE] 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:443 switch_core_session_run() (sofia/external/0123456789) State CONSUME_MEDIA going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_HANGUP 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/external/0123456789) State HANGUP 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:269 sofia_on_hangup() Channel sofia/external/0123456789 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:47 switch_core_standard_on_hangup() Standard HANGUP sofia/external/0123456789, cause: NORMAL_TEMPORARY_FAILURE 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/external/0123456789) State HANGUP going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 22 (sofia/external/0123456789) Locked, Waiting on external entities 2009-03-16 18:29:42 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 22 (sofia/external/0123456789) Ended 2009-03-16 18:29:42 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/external/0123456789 [CS_HANGUP] 2009-03-16 18:29:42 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/ludovic@192.168.1.1:5061 [KILL] 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/ludovic@192.168.1.1:5061 [BREAK] 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State EXECUTE going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/internal/ludovic@192.168.1.1:5061 Running State Change CS_HANGUP 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State HANGUP 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:235 sofia_on_hangup() sofia/internal/ludovic@192.168.1.1:5061 Overriding SIP cause 503 with 503 from the other leg 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:269 sofia_on_hangup() Channel sofia/internal/ludovic@192.168.1.1:5061 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2009-03-16 18:29:42 [DEBUG] mod_sofia.c:343 sofia_on_hangup() Responding to INVITE with: 503 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:47 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/ludovic@192.168.1.1:5061, cause: NORMAL_TEMPORARY_FAILURE 2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State HANGUP going to sleep 2009-03-16 18:29:42 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 21 (sofia/internal/ludovic@192.168.1.1:5061) Locked, Waiting on external entities 2009-03-16 18:29:42 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 21 (sofia/internal/ludovic@192.168.1.1:5061) Ended 2009-03-16 18:29:42 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/ludovic@192.168.1.1:5061 [CS_HANGUP] 2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [503][Service Unavailable] session: n/a
This would be one thing to look at your DNS name isn't resolving correctly.. you might consider using dynamic DNS and you can then set the them to "host:myhost.dyndns.org" /b On Mar 16, 2009, at 12:55 PM, ludovic wrote:
2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_r_invite] status [503][DNS Error] session: sofia/external/ 0123456789 2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [503][DNS Error] session: sofia/external/ 0123456789
What I provided you was an example. I don't think you understood what I was talking about. In the settings for ext-sip-ip and ext-rtp-ip you'll have to use something like "host:yourdyndnshostname.blah.tld" Then set the sip-ip and rtp-ip to what ever is auto detected. /b On Mar 17, 2009, at 6:22 AM, ludovic wrote:
Thanks. It seems that it comes from my sip provider. when using my_host as my hostname, reg fails when using my_host.com as my hostname, reg succeeds (my_host.com does not exist as a domain internet) when using ip address, reg succeeds.
Tested with version 1.0.3
Is it a way to force the IP address to be used in SIP header instead of hostname ?
Thanks
Ludovic
I checked, I don't see sw1.freephonie.net in the logs trying to resolve it... and the SRV records are all correct as are the naptr records which is shocking ;) /b On Mar 17, 2009, at 1:16 PM, ludovic wrote:
I understood the example. What I mean is that my DNS issue comes from sofia-sip and my sip provider (freephonie.net) name resolution which fails when calling whereas it is well resolved during the registration process. Here is a trace : 2009-03-17 18:47:28 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/external/0123456789 [ae9c2108-131b-11de-8a2f-1fafbaa54120] nua: nh_create_handle: entering ; nua: nua_handle_bind: entering nua: nua_invite: entering nua: nua_stack_set_params: entering soa_clone(static::0x100abd98, 0x100a8738, 0x100c6748) called soa_set_params(static::0x100b4728, ...) called soa_set_params(static::0x100b4728, ...) called soa_set_user_sdp(static::0x100b4728, (nil), 0x10106364, -1) called soa_set_capability_sdp(static::0x100b4728, (nil), 0x10106364, -1) called su_localinfo: if lo with index 1 su_localinfo: if lan1 with index 18 su_localinfo: if ppp1 with index 23 nta_leg_tcreate(0x100c7f30) nua(0x100c6748): adding session usage soa_init_offer_answer(static::0x100b4728) called soa_generate_offer(static::0x100b4728, 0) called soa_static_offer_answer_action(0x100b4728, soa_generate_offer): called soa_static(0x100b4728, soa_generate_offer): generating local description su_localinfo: if lo with index 1 su_localinfo: if lan1 with index 18 su_localinfo: if ppp1 with index 23 soa_static(0x100b4728, soa_generate_offer): upgrade with local description soa_sdp_mode_set(0x7dbfd850, (nil), ""): called soa_static(0x100b4728, soa_generate_offer): storing local description soa_get_local_sdp(static::0x100b4728, [(nil)], [0x7dbff980], [0x7dbff984]) called nta: selecting scheme sip sres_cache_get(0x100abfc0, NAPTR, "freephonie.net.") called rr found in cache: freephonie.net. 35 sres_cache_get(0x100abfc0, NAPTR, "freephonie.net.") returned 1 entries nta: for "freephonie.net" query "freephonie.net" NAPTR (cached) nta: freephonie.net. IN NAPTR 100 100 "s" "SIP+D2U" "" _sip_udp.freephonie.net. sres_cache_get(0x100abfc0, SRV, "_sip_udp.freephonie.net.") called rr found in cache: _sip_udp.freephonie.net. 33 sres_cache_get(0x100abfc0, SRV, "_sip_udp.freephonie.net.") returned 1 entries nta: for "freephonie.net" query "_sip_udp.freephonie.net." SRV (cached) nta: timer set to 32000 ms nua(0x100c6748): call state changed: init -> calling, sent offer soa_get_local_sdp(static::0x100b4728, [0x7dbff988], [0x7dbff98c], [(nil)]) called nua: nua_application_event: entering nua(0x100c6748): call state changed: calling -> init nua(0x100c6748): removing session usage soa_destroy(static::0x100b4728) called
participants (2)
-
Brian West
-
ludovic