Attachment 35675 Details for Bug 28123 – sofia.log

sofia.log

sofia.log (text/x-log), 34.97 KB, created by SiB on 2010-05-15 10:48:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: SiB

Created: 2010-05-15 10:48:51 UTC

Size: 34.97 KB

patch

obsolete

>tp-glib-DEBUG: 15.05.2010 19:34:14.785521: started version 0.6.2 (telepathy-glib version 0.10.1)
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.785682: tpsip_connection_manager_new_connection: New SIP connection to latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.786175: tpsip_connection_manager_new_connection: set outbound proxy address to <sip:sip.halonet.pl>, based on <latek18@sip.halonet.pl>
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.790478: tpsip_connection_start_connecting: self_handle = 1, sip_address = sip:latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.790498: tpsip_conn_get_local_url: local binding expressed as <sip:0:*>
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.791198: priv_nua_set_outbound_options: setting outbound options natify use-rport no-options-keepalive
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.791208: tpsip_conn_update_nua_keepalive_interval: setting keepalive interval to 0 msec
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.791268: tpsip_conn_discover_stun_server: creating a new STUN SRV query for domain sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.791337: tpsip_connection_start_connecting: initialized a Sofia-SIP NUA at address 0x1f83110
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793447: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793453: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793457: tpsip_connection_sofia_callback: dispatching to connection 0x1f80030 (unbound handle (nil))
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793467: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x1f80030 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793469: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793474: tpsip_connection_sofia_callback: event nua_r_get_params: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793498: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793500: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793503: tpsip_connection_sofia_callback: dispatching to connection 0x1f80030 (unbound handle (nil))
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793508: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x1f80030 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793509: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793513: tpsip_connection_sofia_callback: event nua_r_set_params: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793515: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793521: tpsip_connection_sofia_callback: dispatching to connection 0x1f80030 (unbound handle (nil))
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793525: tpsip_connection_sofia_callback: event nua_r_set_params for target 0x1f80030 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793529: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:34:14.793531: tpsip_connection_sofia_callback: event nua_r_get_params: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913491: tpsip_connection_sofia_callback: event nua_i_outbound: 101 NAT detected
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913511: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913517: tpsip_connection_sofia_callback: dispatching to target 0x1f80030 (handle 0x1f8a770)
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913538: tpsip_connection_sofia_callback: event nua_i_outbound for target 0x1f80030 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913542: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913552: tpsip_connection_sofia_callback: event nua_r_register: 401 Unauthorized
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913556: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913561: tpsip_connection_sofia_callback: dispatching to target 0x1f80030 (handle 0x1f8a770)
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.913575: priv_handle_auth: response presents an authentication challenge
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.913584: priv_handle_auth: using the primary auth credentials
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.913593: priv_handle_auth: server authenticating user='latek18' realm="sip.halonet.pl"
>tpsip/events-DEBUG: 15.05.2010 19:34:14.913619: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:34:14.934740: tpsip_connection_sofia_callback: event nua_r_register: 200 OK
>tpsip/events-DEBUG: 15.05.2010 19:34:14.934748: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:14.934753: tpsip_connection_sofia_callback: dispatching to target 0x1f80030 (handle 0x1f8a770)
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.934768: tpsip_connection_nua_r_register_cb: succesfully registered to the network
>tpsip/events-DEBUG: 15.05.2010 19:34:14.934923: tpsip_connection_sofia_callback: exit
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.939265: conn_get_alias: handle 1 got alias latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:34:14.940042: tpsip_connection_set_aliases: using default alias for self
>tpsip/sofia-DEBUG: 15.05.2010 19:34:26.218067: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:34:46.933636: outbound(0x1f8a770): FAILED to validate <sip:latek18@82.210.157.177:50013;transport=udp>
>tpsip/sofia-DEBUG: 15.05.2010 19:34:46.933650: outbound(0x1f8a770): FAILED with 408 Request Timeout
>tpsip/events-DEBUG: 15.05.2010 19:34:46.933687: tpsip_connection_sofia_callback: event nua_i_outbound: 408 Request Timeout
>tpsip/events-DEBUG: 15.05.2010 19:34:46.933695: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 1
>tpsip/events-DEBUG: 15.05.2010 19:34:46.933698: tpsip_connection_sofia_callback: dispatching to target 0x1f80030 (handle 0x1f8a770)
>tpsip/events-DEBUG: 15.05.2010 19:34:46.933722: tpsip_connection_sofia_callback: event nua_i_outbound for target 0x1f80030 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:34:46.933726: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:35:26.220526: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:36:26.273379: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:37:26.279723: nta_agent: tport: Bad message
>tpsip/connection-DEBUG: 15.05.2010 19:37:33.921327: conn_get_alias: handle 2 got alias 691910027@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:37:34.121190: conn_get_alias: handle 1 got alias latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:37:34.217036: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel0
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.217365: tpsip_media_channel_class_init: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.217605: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel0
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.218341: priv_outbound_call: making outbound call - setting peer handle to 2
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.218413: priv_create_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.218477: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.218657: priv_create_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.269525: tpsip_media_channel_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.269581: priv_outbound_call: session already exists
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.269622: tpsip_media_session_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.269659: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.270102: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.270160: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.270247: tpsip_media_channel_request_streams: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.270442: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.276002: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.476897: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:34.477044: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401045: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401315: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401362: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401401: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401442: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401525: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.401773: push_active_candidate_pair: enter
>tpsip/events-DEBUG: 15.05.2010 19:37:36.402493: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:37:36.402546: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:36.402607: tpsip_connection_sofia_callback: dispatching to target 0x1fa5020 (handle 0x1fa0050)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.402661: priv_nua_i_state_cb: call with handle 0x1fa0050 is calling
>tpsip/events-DEBUG: 15.05.2010 19:37:36.402702: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:36.483983: tpsip_connection_sofia_callback: event nua_r_invite: 407 Proxy Authentication Required
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484067: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484110: tpsip_connection_sofia_callback: dispatching to target 0x1fa5020 (handle 0x1fa0050)
>tpsip/connection-DEBUG: 15.05.2010 19:37:36.484220: priv_handle_auth: response presents an authentication challenge
>tpsip/connection-DEBUG: 15.05.2010 19:37:36.484261: priv_handle_auth: using the primary auth credentials
>tpsip/connection-DEBUG: 15.05.2010 19:37:36.484301: priv_handle_auth: proxy authenticating user='latek18' realm="sip.halonet.pl"
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484345: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484643: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484693: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484733: tpsip_connection_sofia_callback: dispatching to target 0x1fa5020 (handle 0x1fa0050)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.484787: priv_nua_i_state_cb: call with handle 0x1fa0050 is calling
>tpsip/events-DEBUG: 15.05.2010 19:37:36.484827: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:37:36.495560: nta: INVITE (130854193): No route to host (113) with tcp/[194.9.25.21]:5060
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496315: tpsip_connection_sofia_callback: event nua_r_invite: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496376: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496417: tpsip_connection_sofia_callback: dispatching to target 0x1fa5020 (handle 0x1fa0050)
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496465: tpsip_connection_sofia_callback: event nua_r_invite for target 0x1fa5020 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496505: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496550: tpsip_connection_sofia_callback: event nua_i_state: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496588: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:36.496628: tpsip_connection_sofia_callback: dispatching to target 0x1fa5020 (handle 0x1fa0050)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.496675: priv_nua_i_state_cb: call with handle 0x1fa0050 is terminated
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.496949: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497030: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497092: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497133: tpsip_media_session_change_state: destroying the NUA handle 0x1fa0050
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497179: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497227: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497270: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497308: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497400: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497445: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497484: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497524: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497601: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:36.497653: tpsip_media_channel_finalize: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:36.497693: tpsip_connection_sofia_callback: exit
>tpsip/connection-DEBUG: 15.05.2010 19:37:41.109883: conn_get_alias: handle 1 got alias latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:37:41.129854: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel1
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.129996: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel1
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.130709: priv_outbound_call: making outbound call - setting peer handle to 2
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.130778: priv_create_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.130842: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.130943: priv_create_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151376: tpsip_media_channel_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151431: priv_outbound_call: session already exists
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151470: tpsip_media_session_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151510: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151720: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151777: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.151856: tpsip_media_channel_request_streams: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.153723: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.155917: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.285042: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:41.285305: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401190: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401401: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401447: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401485: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401526: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401601: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.401854: push_active_candidate_pair: enter
>tpsip/events-DEBUG: 15.05.2010 19:37:43.402441: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:37:43.402493: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:43.402533: tpsip_connection_sofia_callback: dispatching to target 0x1fa50d0 (handle 0x1fa00c0)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.402607: priv_nua_i_state_cb: call with handle 0x1fa00c0 is calling
>tpsip/events-DEBUG: 15.05.2010 19:37:43.404386: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411078: tpsip_connection_sofia_callback: event nua_r_invite: 407 Proxy Authentication Required
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411164: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411205: tpsip_connection_sofia_callback: dispatching to target 0x1fa50d0 (handle 0x1fa00c0)
>tpsip/connection-DEBUG: 15.05.2010 19:37:43.411251: priv_handle_auth: response presents an authentication challenge
>tpsip/connection-DEBUG: 15.05.2010 19:37:43.411358: priv_handle_auth: using the primary auth credentials
>tpsip/connection-DEBUG: 15.05.2010 19:37:43.411398: priv_handle_auth: proxy authenticating user='latek18' realm="sip.halonet.pl"
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411442: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411757: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411807: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411847: tpsip_connection_sofia_callback: dispatching to target 0x1fa50d0 (handle 0x1fa00c0)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.411896: priv_nua_i_state_cb: call with handle 0x1fa00c0 is calling
>tpsip/events-DEBUG: 15.05.2010 19:37:43.411936: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:37:43.422061: nta: INVITE (130854196): No route to host (113) with tcp/[194.9.25.21]:5060
>tpsip/events-DEBUG: 15.05.2010 19:37:43.422254: tpsip_connection_sofia_callback: event nua_r_invite: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:37:43.422300: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:43.422338: tpsip_connection_sofia_callback: dispatching to target 0x1fa50d0 (handle 0x1fa00c0)
>tpsip/events-DEBUG: 15.05.2010 19:37:43.422385: tpsip_connection_sofia_callback: event nua_r_invite for target 0x1fa50d0 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:37:43.422434: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:43.423409: tpsip_connection_sofia_callback: event nua_i_state: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:37:43.423454: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:37:43.423495: tpsip_connection_sofia_callback: dispatching to target 0x1fa50d0 (handle 0x1fa00c0)
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.423547: priv_nua_i_state_cb: call with handle 0x1fa00c0 is terminated
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.423821: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.423903: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.423979: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424020: tpsip_media_session_change_state: destroying the NUA handle 0x1fa00c0
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424067: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424117: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424156: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424194: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424278: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424323: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424362: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424401: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424457: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:37:43.424504: tpsip_media_channel_finalize: exit
>tpsip/events-DEBUG: 15.05.2010 19:37:43.424541: tpsip_connection_sofia_callback: exit
>tpsip/connection-DEBUG: 15.05.2010 19:38:11.678519: conn_get_alias: handle 2 got alias 691910027@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:38:11.839523: conn_get_alias: handle 1 got alias latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:38:11.872195: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.872323: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.873023: priv_outbound_call: making outbound call - setting peer handle to 2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.873097: priv_create_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.873162: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.873262: priv_create_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.901866: tpsip_media_channel_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.901920: priv_outbound_call: session already exists
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.901958: tpsip_media_session_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.901998: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.902194: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.902250: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.902329: tpsip_media_channel_request_streams: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.903831: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:11.908698: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:12.146483: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:12.146612: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401079: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401293: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401338: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401376: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401417: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401493: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.401756: push_active_candidate_pair: enter
>tpsip/events-DEBUG: 15.05.2010 19:38:14.402329: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:38:14.402384: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:14.402421: tpsip_connection_sofia_callback: dispatching to target 0x1fa5180 (handle 0x1fb6c20)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.402470: priv_nua_i_state_cb: call with handle 0x1fb6c20 is calling
>tpsip/events-DEBUG: 15.05.2010 19:38:14.402509: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421169: tpsip_connection_sofia_callback: event nua_r_invite: 407 Proxy Authentication Required
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421252: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421293: tpsip_connection_sofia_callback: dispatching to target 0x1fa5180 (handle 0x1fb6c20)
>tpsip/connection-DEBUG: 15.05.2010 19:38:14.421338: priv_handle_auth: response presents an authentication challenge
>tpsip/connection-DEBUG: 15.05.2010 19:38:14.421377: priv_handle_auth: using the primary auth credentials
>tpsip/connection-DEBUG: 15.05.2010 19:38:14.421416: priv_handle_auth: proxy authenticating user='latek18' realm="sip.halonet.pl"
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421461: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421771: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421818: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:14.421855: tpsip_connection_sofia_callback: dispatching to target 0x1fa5180 (handle 0x1fb6c20)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.421967: priv_nua_i_state_cb: call with handle 0x1fb6c20 is calling
>tpsip/events-DEBUG: 15.05.2010 19:38:14.422007: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:38:14.458142: nta: INVITE (130854212): No route to host (113) with tcp/[194.9.25.21]:5060
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458353: tpsip_connection_sofia_callback: event nua_r_invite: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458398: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458436: tpsip_connection_sofia_callback: dispatching to target 0x1fa5180 (handle 0x1fb6c20)
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458484: tpsip_connection_sofia_callback: event nua_r_invite for target 0x1fa5180 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458523: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458564: tpsip_connection_sofia_callback: event nua_i_state: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458601: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:14.458640: tpsip_connection_sofia_callback: dispatching to target 0x1fa5180 (handle 0x1fb6c20)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.458684: priv_nua_i_state_cb: call with handle 0x1fb6c20 is terminated
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.458946: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459021: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459084: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459124: tpsip_media_session_change_state: destroying the NUA handle 0x1fb6c20
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459167: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459213: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459252: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459287: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459366: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459408: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459446: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459486: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459537: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:14.459580: tpsip_media_channel_finalize: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:14.459618: tpsip_connection_sofia_callback: exit
>tpsip/connection-DEBUG: 15.05.2010 19:38:17.425393: conn_get_alias: handle 1 got alias latek18@sip.halonet.pl
>tpsip/connection-DEBUG: 15.05.2010 19:38:17.443841: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel3
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.443975: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/latek18_40sip_2ehalonet_2epl/MediaChannel3
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.444675: priv_outbound_call: making outbound call - setting peer handle to 2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.444745: priv_create_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.444809: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.444911: priv_create_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465400: tpsip_media_channel_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465456: priv_outbound_call: session already exists
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465496: tpsip_media_session_request_streams: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465533: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465729: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465785: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.465879: tpsip_media_channel_request_streams: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.468350: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.470015: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.636478: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:17.636610: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401140: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401345: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401388: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401427: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401467: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401537: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.401782: push_active_candidate_pair: enter
>tpsip/events-DEBUG: 15.05.2010 19:38:19.402312: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:38:19.402360: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:19.402399: tpsip_connection_sofia_callback: dispatching to target 0x1fa5230 (handle 0x1fafa10)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.402446: priv_nua_i_state_cb: call with handle 0x1fafa10 is calling
>tpsip/events-DEBUG: 15.05.2010 19:38:19.402486: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413244: tpsip_connection_sofia_callback: event nua_r_invite: 407 Proxy Authentication Required
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413325: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413367: tpsip_connection_sofia_callback: dispatching to target 0x1fa5230 (handle 0x1fafa10)
>tpsip/connection-DEBUG: 15.05.2010 19:38:19.413413: priv_handle_auth: response presents an authentication challenge
>tpsip/connection-DEBUG: 15.05.2010 19:38:19.413456: priv_handle_auth: using the primary auth credentials
>tpsip/connection-DEBUG: 15.05.2010 19:38:19.413496: priv_handle_auth: proxy authenticating user='latek18' realm="sip.halonet.pl"
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413541: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413877: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413924: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:19.413964: tpsip_connection_sofia_callback: dispatching to target 0x1fa5230 (handle 0x1fafa10)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.414076: priv_nua_i_state_cb: call with handle 0x1fafa10 is calling
>tpsip/events-DEBUG: 15.05.2010 19:38:19.414115: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:38:19.448580: nta: INVITE (130854214): No route to host (113) with tcp/[194.9.25.21]:5060
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448772: tpsip_connection_sofia_callback: event nua_r_invite: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448817: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448856: tpsip_connection_sofia_callback: dispatching to target 0x1fa5230 (handle 0x1fafa10)
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448905: tpsip_connection_sofia_callback: event nua_r_invite for target 0x1fa5230 was not consumed
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448944: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:19.448986: tpsip_connection_sofia_callback: event nua_i_state: 503 Service Unavailable
>tpsip/events-DEBUG: 15.05.2010 19:38:19.449024: tpsip_connection_sofia_callback: connection 0x1f80030, refcount 2
>tpsip/events-DEBUG: 15.05.2010 19:38:19.449063: tpsip_connection_sofia_callback: dispatching to target 0x1fa5230 (handle 0x1fafa10)
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.449110: priv_nua_i_state_cb: call with handle 0x1fafa10 is terminated
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.450732: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.450814: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.450881: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.450921: tpsip_media_session_change_state: destroying the NUA handle 0x1fafa10
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.450969: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451020: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451061: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451100: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451184: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451229: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451267: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451308: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451363: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 15.05.2010 19:38:19.451407: tpsip_media_channel_finalize: exit
>tpsip/events-DEBUG: 15.05.2010 19:38:19.451447: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 15.05.2010 19:38:26.273014: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:39:26.357662: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:40:26.402587: nta_agent: tport: Bad message
>tpsip/sofia-DEBUG: 15.05.2010 19:41:26.437576: nta_agent: tport: Bad message

Actions: View

Attachments on bug 28123: 35675 | 35676 | 35704

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.