tpsip/events-DEBUG: 08/30/2010 15:34:02.224378: tpsip_connection_sofia_callback: event nua_i_invite: 100 Trying tpsip/events-DEBUG: 08/30/2010 15:34:02.224602: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 1 tpsip/events-DEBUG: 08/30/2010 15:34:02.224782: tpsip_connection_sofia_callback: dispatching to connection 0x82fc930 (unbound handle 0x8315a00) tpsip/connection-DEBUG: 08/30/2010 15:34:02.224987: tpsip_nua_i_invite_cb: Got incoming invite from tpsip/connection-DEBUG: 08/30/2010 15:34:02.225142: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/laptop_40sip_2einternal/MediaChannel3 tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.225534: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/laptop_40sip_2einternal/MediaChannel3 tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.226694: priv_create_session: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.226903: priv_create_session: allocating session, peer=3 tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.227237: priv_create_session: exit tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.227395: priv_session_state_changed_cb: enter tpsip/events-DEBUG: 08/30/2010 15:34:02.228302: tpsip_connection_sofia_callback: exit tpsip/events-DEBUG: 08/30/2010 15:34:02.228451: tpsip_connection_sofia_callback: event nua_i_state: 100 Trying tpsip/events-DEBUG: 08/30/2010 15:34:02.228591: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:02.228760: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.228914: tpsip_media_session_set_remote_media: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.229074: tpsip_media_session_add_stream: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233200: tpsip_media_session_add_stream: exit tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233350: priv_update_remote_media: setting remote SDP for stream 0 tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233494: tpsip_media_stream_set_remote_media: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233639: push_remote_candidates: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233802: push_remote_candidates: the stream engine is not ready, SetRemoteCandidateList is pending tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.233946: push_remote_codecs: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234087: push_remote_codecs: the stream engine is not ready, SetRemoteCodecs is pending tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234227: push_active_candidate_pair: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234370: priv_update_remote_hold: the session is remotely unheld tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234513: priv_update_remote_media: exit tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234656: priv_request_response_step: there are local streams not ready, postponed tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234801: priv_nua_i_state_cb: call with handle 0x8315a00 is received tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.234945: priv_initial_media_properties_from_sdp: has initial audio tpsip/events-DEBUG: 08/30/2010 15:34:02.236082: tpsip_connection_sofia_callback: exit tpsip/events-DEBUG: 08/30/2010 15:34:02.236541: tpsip_connection_sofia_callback: event nua_i_state: 180 Ringing tpsip/events-DEBUG: 08/30/2010 15:34:02.236704: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:02.236861: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/media-channel-DEBUG: 08/30/2010 15:34:02.237021: priv_nua_i_state_cb: call with handle 0x8315a00 is early tpsip/events-DEBUG: 08/30/2010 15:34:02.237169: tpsip_connection_sofia_callback: exit tpsip/connection-DEBUG: 08/30/2010 15:34:02.242480: conn_get_alias: handle 3 got alias icdk@sip.internal tpsip/connection-DEBUG: 08/30/2010 15:34:17.823540: conn_get_alias: handle 1 got alias laptop@sip.internal tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.846874: _tpsip_media_channel_add_member: mixin->self_handle=1, handle=1 tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.846925: _tpsip_media_channel_add_member: accepting an incoming invite tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.846970: tpsip_media_stream_apply_pending_direction: set direction 3, pending send flags 0 tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.847048: tpsip_media_stream_apply_pending_direction: remote end ready to receive tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.847090: tpsip_media_stream_set_sending: set sending to TRUE tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.847132: priv_request_response_step: there are local streams not ready, postponed tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.893141: tpsip_media_channel_get_session_handlers: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:17.893991: tpsip_media_session_ready: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75066: tpsip_media_stream_ready: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75176: push_remote_candidates: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75237: push_remote_candidates: remote RTP address=<88.181.168.120>, port=<7078> tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75299: push_remote_candidates: remote RTCP address=<88.181.168.120>, port=<7079> tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75366: push_remote_candidates: emitting SetRemoteCandidateList with L1 tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75517: push_remote_codecs: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.75834: push_active_candidate_pair: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.113811: tpsip_media_stream_supported_codecs: got codec intersection containing 5 codecs from stream-engine tpsip/media-channel-DEBUG: 08/30/2010 15:34:18.113867: priv_request_response_step: there are local streams not ready, postponed tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.367198: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1 tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.367724: tpsip_media_stream_stream_state: changing stream state from 0 to 2 tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.373388: tpsip_media_stream_native_candidates_prepared: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.373893: priv_stream_ready_cb: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.374049: tpsip_media_stream_set_playing: set playing to TRUE tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.374260: tpsip_media_stream_apply_pending_direction: remote end ready to receive tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.374414: priv_session_state_changed_cb: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.375358: push_active_candidate_pair: enter tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.375503: push_active_candidate_pair: emitting SetActiveCandidatePair for L1-L1 tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.375844: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1 tpsip/events-DEBUG: 08/30/2010 15:34:20.377168: tpsip_connection_sofia_callback: event nua_i_state: 200 OK tpsip/events-DEBUG: 08/30/2010 15:34:20.377353: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:20.377821: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/media-channel-DEBUG: 08/30/2010 15:34:20.377993: priv_nua_i_state_cb: call with handle 0x8315a00 is completed tpsip/events-DEBUG: 08/30/2010 15:34:20.378139: tpsip_connection_sofia_callback: exit tpsip/events-DEBUG: 08/30/2010 15:34:52.377610: tpsip_connection_sofia_callback: event nua_i_error: 408 ACK Timeout tpsip/events-DEBUG: 08/30/2010 15:34:52.377870: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:52.378020: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/events-DEBUG: 08/30/2010 15:34:52.378184: tpsip_connection_sofia_callback: event nua_i_error for target 0x830db50 was not consumed tpsip/events-DEBUG: 08/30/2010 15:34:52.378978: tpsip_connection_sofia_callback: exit tpsip/events-DEBUG: 08/30/2010 15:34:52.379151: tpsip_connection_sofia_callback: event nua_i_state: 000 ACK Timeout tpsip/events-DEBUG: 08/30/2010 15:34:52.379297: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:52.379441: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/media-channel-DEBUG: 08/30/2010 15:34:52.379601: priv_nua_i_state_cb: call with handle 0x8315a00 is terminating tpsip/events-DEBUG: 08/30/2010 15:34:52.379779: tpsip_connection_sofia_callback: exit tpsip/events-DEBUG: 08/30/2010 15:34:52.419058: tpsip_connection_sofia_callback: event nua_r_bye: 407 Proxy Authentication Required tpsip/events-DEBUG: 08/30/2010 15:34:52.419117: tpsip_connection_sofia_callback: connection 0x82fc930, refcount 2 tpsip/events-DEBUG: 08/30/2010 15:34:52.420126: tpsip_connection_sofia_callback: dispatching to target 0x830db50 (handle 0x8315a00) tpsip/connection-DEBUG: 08/30/2010 15:34:52.420186: priv_handle_auth: response presents an authentication challenge tpsip/connection-DEBUG: 08/30/2010 15:34:52.420229: priv_handle_auth: using the primary auth credentials tpsip/connection-DEBUG: 08/30/2010 15:34:52.420272: priv_handle_auth: proxy authenticating user='laptop' realm="sip.internal" tpsip/events-DEBUG: 08/30/2010 15:34:52.420324: tpsip_connection_sofia_callback: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.95928: tpsip_media_stream_error: StreamHandler.Error called: 0 UI stopped channel tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.95998: priv_stream_close_cb: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.96075: tpsip_media_stream_dispose: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.96134: tpsip_media_stream_finalize: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.101766: tpsip_media_channel_close: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.101818: tpsip_media_session_terminate: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.101861: tpsip_media_session_terminate: sending BYE tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.101907: tpsip_media_session_change_state: destroying the NUA handle 0x8315a00 tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.101953: priv_session_state_changed_cb: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102180: priv_destroy_session: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102224: priv_destroy_session: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102263: tpsip_media_channel_close: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102305: tpsip_media_session_dispose: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102351: tpsip_media_session_dispose: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102396: tpsip_media_session_finalize: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102474: tpsip_media_channel_dispose: enter tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102531: tpsip_media_channel_dispose: exit tpsip/media-channel-DEBUG: 08/30/2010 15:35:18.102579: tpsip_media_channel_finalize: exit