I got my SIP config file enabled and since I have to wait for my ATA to arrive (there was an order mixup) I decided to try with a softphone. I am using Empathy (together with Sofia-SIP) under Linux, and it seems I can receive calls, but when I try to dial out I get a voice telling me "This password is not valid". I am using the correct SIP password, so I don't know what might be going on.
This is what the debug information says (in case it is useful):
Code: Select all
tp-glib-DEBUG: 05/07/10 11:15:02.26535: started version 0.6.3 (telepathy-glib version 0.11.6)
tpsip/connection-DEBUG: 05/07/10 11:15:02.27232: tpsip_connection_manager_new_connection: New SIP connection to 15144479590@voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:02.72287: tpsip_connection_start_connecting: self_handle = 1, sip_address = sip:15144479590@voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:02.72362: tpsip_conn_get_local_url: local binding expressed as <sip:0:*>
tpsip/connection-DEBUG: 05/07/10 11:15:02.100189: priv_nua_set_outbound_options: setting outbound options natify use-rport no-options-keepalive
tpsip/connection-DEBUG: 05/07/10 11:15:02.100233: tpsip_conn_update_nua_keepalive_interval: setting keepalive interval to 180000 msec
tpsip/connection-DEBUG: 05/07/10 11:15:02.100553: tpsip_conn_discover_stun_server: creating a new STUN SRV query for domain voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:02.100785: tpsip_connection_start_connecting: initialized a Sofia-SIP NUA at address 0x207d000
tpsip/events-DEBUG: 05/07/10 11:15:02.128108: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.128129: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.128154: tpsip_connection_sofia_callback: dispatching to connection 0x207a030 (unbound handle (nil))
tpsip/events-DEBUG: 05/07/10 11:15:02.128199: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:02.128209: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:02.128233: tpsip_connection_sofia_callback: event nua_r_get_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.128324: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.128338: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.128350: tpsip_connection_sofia_callback: dispatching to connection 0x207a030 (unbound handle (nil))
tpsip/events-DEBUG: 05/07/10 11:15:02.128374: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:02.128384: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:02.128396: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.128407: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.128418: tpsip_connection_sofia_callback: dispatching to connection 0x207a030 (unbound handle (nil))
tpsip/events-DEBUG: 05/07/10 11:15:02.128438: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:02.128448: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:02.128463: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.128473: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.128485: tpsip_connection_sofia_callback: dispatching to connection 0x207a030 (unbound handle (nil))
tpsip/events-DEBUG: 05/07/10 11:15:02.128506: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:02.128515: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:02.128528: tpsip_connection_sofia_callback: event nua_r_get_params: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.160485: tpsip_connection_sofia_callback: event nua_r_register: 401 Unauthorized
tpsip/events-DEBUG: 05/07/10 11:15:02.160507: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.160521: tpsip_connection_sofia_callback: dispatching to target 0x207a030 (handle 0x2084950)
tpsip/connection-DEBUG: 05/07/10 11:15:02.160572: priv_handle_auth: response presents an authentication challenge
tpsip/connection-DEBUG: 05/07/10 11:15:02.160593: priv_handle_auth: using the primary auth credentials
tpsip/connection-DEBUG: 05/07/10 11:15:02.160609: priv_handle_auth: server authenticating user='15144479590' realm="voip.freephoneline.ca"
tpsip/events-DEBUG: 05/07/10 11:15:02.160679: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:02.224905: tpsip_connection_sofia_callback: event nua_r_register: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.224929: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.224940: tpsip_connection_sofia_callback: dispatching to target 0x207a030 (handle 0x2084950)
tpsip/connection-DEBUG: 05/07/10 11:15:02.224968: tpsip_connection_nua_r_register_cb: succesfully registered to the network
tpsip/events-DEBUG: 05/07/10 11:15:02.225265: tpsip_connection_sofia_callback: exit
tpsip/connection-DEBUG: 05/07/10 11:15:02.233298: conn_get_alias: handle 1 got alias 15144479590@voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:02.235511: tpsip_connection_set_aliases: using default alias for self
tpsip/sofia-DEBUG: 05/07/10 11:15:02.260423: outbound(0x2084950): FAILED to validate <sip:15144479590@142.157.108.38:34736>
tpsip/sofia-DEBUG: 05/07/10 11:15:02.260448: outbound(0x2084950): FAILED with 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.261877: tpsip_connection_sofia_callback: event nua_i_outbound: 200 OK
tpsip/events-DEBUG: 05/07/10 11:15:02.261898: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:15:02.261909: tpsip_connection_sofia_callback: dispatching to target 0x207a030 (handle 0x2084950)
tpsip/events-DEBUG: 05/07/10 11:15:02.261939: tpsip_connection_sofia_callback: event nua_i_outbound for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:02.261949: tpsip_connection_sofia_callback: exit
tpsip/connection-DEBUG: 05/07/10 11:15:28.269903: conn_get_alias: handle 2 got alias 5142977826@voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:29.197518: conn_get_alias: handle 1 got alias 15144479590@voip.freephoneline.ca
tpsip/connection-DEBUG: 05/07/10 11:15:29.395663: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/_315144479590_40voip_2efreephoneline_2eca/MediaChannel0
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.396891: tpsip_media_channel_class_init: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.398320: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/_315144479590_40voip_2efreephoneline_2eca/MediaChannel0
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.407816: priv_outbound_call: making outbound call - setting peer handle to 2
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.407927: priv_create_session: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.408026: priv_create_session: allocating session, peer=2
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.408752: priv_create_session: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.489986: tpsip_media_channel_request_streams: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.490016: priv_outbound_call: session already exists
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.490046: tpsip_media_session_request_streams: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.490060: tpsip_media_session_add_stream: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.494592: tpsip_media_session_add_stream: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.494697: priv_request_response_step: there are local streams not ready, postponed
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.494900: tpsip_media_channel_request_streams: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.500648: tpsip_media_channel_get_session_handlers: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:29.584028: tpsip_media_session_ready: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:30.80257: tpsip_media_stream_ready: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:30.80790: push_active_candidate_pair: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.222876: tpsip_media_stream_native_candidates_prepared: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.223918: priv_request_response_step: there are local streams not ready, postponed
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.223959: priv_stream_ready_cb: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.223978: priv_session_invite: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.224005: tpsip_media_stream_set_playing: set playing to TRUE
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.224359: priv_session_state_changed_cb: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.226172: push_active_candidate_pair: enter
tpsip/events-DEBUG: 05/07/10 11:15:32.230330: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
tpsip/events-DEBUG: 05/07/10 11:15:32.230361: tpsip_connection_sofia_callback: connection 0x207a030, refcount 2
tpsip/events-DEBUG: 05/07/10 11:15:32.230381: tpsip_connection_sofia_callback: dispatching to target 0x2099010 (handle 0x209a4b0)
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.230463: priv_nua_i_state_cb: call with handle 0x209a4b0 is calling
tpsip/events-DEBUG: 05/07/10 11:15:32.230490: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:32.269542: tpsip_connection_sofia_callback: event nua_r_invite: 401 Unauthorized
tpsip/events-DEBUG: 05/07/10 11:15:32.269566: tpsip_connection_sofia_callback: connection 0x207a030, refcount 2
tpsip/events-DEBUG: 05/07/10 11:15:32.269579: tpsip_connection_sofia_callback: dispatching to target 0x2099010 (handle 0x209a4b0)
tpsip/connection-DEBUG: 05/07/10 11:15:32.269613: priv_handle_auth: response presents an authentication challenge
tpsip/connection-DEBUG: 05/07/10 11:15:32.269628: priv_handle_auth: using the extra auth credentials
tpsip/connection-DEBUG: 05/07/10 11:15:32.269640: priv_handle_auth: server authenticating user='15144479590' realm="208.65.240.142"
tpsip/events-DEBUG: 05/07/10 11:15:32.269668: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:32.270279: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
tpsip/events-DEBUG: 05/07/10 11:15:32.270293: tpsip_connection_sofia_callback: connection 0x207a030, refcount 2
tpsip/events-DEBUG: 05/07/10 11:15:32.270302: tpsip_connection_sofia_callback: dispatching to target 0x2099010 (handle 0x209a4b0)
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.270337: priv_nua_i_state_cb: call with handle 0x209a4b0 is calling
tpsip/events-DEBUG: 05/07/10 11:15:32.270349: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:32.418648: tpsip_connection_sofia_callback: event nua_r_invite: 183 Session Progress
tpsip/events-DEBUG: 05/07/10 11:15:32.418678: tpsip_connection_sofia_callback: connection 0x207a030, refcount 2
tpsip/events-DEBUG: 05/07/10 11:15:32.418701: tpsip_connection_sofia_callback: dispatching to target 0x2099010 (handle 0x209a4b0)
tpsip/events-DEBUG: 05/07/10 11:15:32.418766: tpsip_connection_sofia_callback: event nua_r_invite for target 0x2099010 was not consumed
tpsip/events-DEBUG: 05/07/10 11:15:32.418787: tpsip_connection_sofia_callback: exit
tpsip/events-DEBUG: 05/07/10 11:15:32.418819: tpsip_connection_sofia_callback: event nua_i_state: 183 Session Progress
tpsip/events-DEBUG: 05/07/10 11:15:32.418842: tpsip_connection_sofia_callback: connection 0x207a030, refcount 2
tpsip/events-DEBUG: 05/07/10 11:15:32.418863: tpsip_connection_sofia_callback: dispatching to target 0x2099010 (handle 0x209a4b0)
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.418916: tpsip_media_session_set_remote_media: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.418956: priv_session_state_changed_cb: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419075: priv_update_remote_media: setting remote SDP for stream 0
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419100: tpsip_media_stream_set_remote_media: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419133: push_remote_candidates: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419331: push_remote_candidates: remote RTP address=<208.65.240.142>, port=<56560>
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419473: push_remote_candidates: remote RTCP address=<208.65.240.142>, port=<56561>
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.419682: push_remote_candidates: emitting SetRemoteCandidateList with L1
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.425813: push_remote_codecs: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.434700: push_active_candidate_pair: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.434721: push_active_candidate_pair: emitting SetActiveCandidatePair for L1-L1
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.435740: tpsip_media_stream_set_direction: set direction 3, pending send flags 0
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.436533: priv_update_remote_hold: the session is remotely unheld
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.436556: priv_update_remote_media: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.436574: priv_nua_i_state_cb: call with handle 0x209a4b0 is proceeding
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.436589: tpsip_media_channel_change_call_state: setting call state 16 for peer 2
tpsip/events-DEBUG: 05/07/10 11:15:32.437293: tpsip_connection_sofia_callback: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.437736: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.443094: tpsip_media_stream_stream_state: changing stream state from 0 to 2
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.444967: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
tpsip/media-channel-DEBUG: 05/07/10 11:15:32.446050: tpsip_media_stream_supported_codecs: got codec intersection containing 5 codecs from stream-engine
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.822464: tpsip_media_stream_error: StreamHandler.Error called: 0 UI stopped channel
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.822583: priv_stream_close_cb: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.822736: tpsip_media_stream_dispose: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.822829: tpsip_media_stream_finalize: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.832259: tpsip_media_channel_close: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.832278: tpsip_media_session_terminate: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.832292: tpsip_media_session_terminate: sending BYE
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.832340: tpsip_media_session_change_state: destroying the NUA handle 0x209a4b0
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.832376: priv_session_state_changed_cb: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833389: priv_destroy_session: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833434: priv_destroy_session: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833458: tpsip_media_channel_close: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833481: tpsip_media_session_dispose: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833528: tpsip_media_session_dispose: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833559: tpsip_media_session_finalize: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.833853: tpsip_media_channel_dispose: enter
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.834012: tpsip_media_channel_dispose: exit
tpsip/media-channel-DEBUG: 05/07/10 11:15:36.834085: tpsip_media_channel_finalize: exit
tpsip/sofia-DEBUG: 05/07/10 11:15:36.840384: nta_outgoing_tcancel: trying to cancel cancelled request
tpsip/sofia-DEBUG: 05/07/10 11:15:36.840415: nta_outgoing_tcancel: trying to cancel cancelled request
tpsip/events-DEBUG: 05/07/10 11:19:26.440170: tpsip_connection_sofia_callback: event nua_r_register: 200 OK
tpsip/events-DEBUG: 05/07/10 11:19:26.440192: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:19:26.440207: tpsip_connection_sofia_callback: dispatching to target 0x207a030 (handle 0x2084950)
tpsip/events-DEBUG: 05/07/10 11:19:26.440249: tpsip_connection_sofia_callback: exit
tpsip/sofia-DEBUG: 05/07/10 11:19:26.474762: outbound(0x2084950): FAILED to validate <sip:15144479590@142.157.108.38:34736>
tpsip/sofia-DEBUG: 05/07/10 11:19:26.474802: outbound(0x2084950): FAILED with 200 OK
tpsip/events-DEBUG: 05/07/10 11:19:26.474898: tpsip_connection_sofia_callback: event nua_i_outbound: 200 OK
tpsip/events-DEBUG: 05/07/10 11:19:26.474919: tpsip_connection_sofia_callback: connection 0x207a030, refcount 1
tpsip/events-DEBUG: 05/07/10 11:19:26.474937: tpsip_connection_sofia_callback: dispatching to target 0x207a030 (handle 0x2084950)
tpsip/events-DEBUG: 05/07/10 11:19:26.474984: tpsip_connection_sofia_callback: event nua_i_outbound for target 0x207a030 was not consumed
tpsip/events-DEBUG: 05/07/10 11:19:26.475003: tpsip_connection_sofia_callback: exit
Pablo B.