Attachment 58232 Details for Bug 47146 – sofiasip debug log

sofiasip debug log

sofiasip (sip)-09-03-12_14-03-15.log (text/plain), 14.03 KB, created by Robert Munteanu on 2012-03-09 04:16:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Robert Munteanu

Created: 2012-03-09 04:16:02 UTC

Size: 14.03 KB

patch

obsolete

>tpsip/connection-DEBUG: 03/09/2012 14:02:47.845474: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/_383888_4010_2e128_2e128_2e40/MediaChannel1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.845580: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/_383888_4010_2e128_2e128_2e40/MediaChannel1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846221: priv_outbound_call: making outbound call - setting peer handle to 2
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846282: priv_create_session: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846321: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846414: priv_create_session: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846463: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:47.846642: tpsip_media_session_add_stream: exit
>tpsip/connection-DEBUG: 03/09/2012 14:02:47.857564: conn_get_alias: handle 2 got alias 83811@10.128.128.40
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:48.7064: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:48.8840: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:48.43358: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:48.43443: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203474: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203758: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203804: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203836: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203871: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.203928: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.204104: push_active_candidate_pair: enter
>tpsip/events-DEBUG: 03/09/2012 14:02:50.204510: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 03/09/2012 14:02:50.204554: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:50.204581: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.204618: priv_nua_i_state_cb: call with handle 0x678b20 is calling
>tpsip/events-DEBUG: 03/09/2012 14:02:50.204641: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:50.299941: tpsip_connection_sofia_callback: event nua_r_invite: 180 Ringing
>tpsip/events-DEBUG: 03/09/2012 14:02:50.299998: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300031: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300067: tpsip_connection_sofia_callback: event nua_r_invite for target 0x64a970 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300096: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300129: tpsip_connection_sofia_callback: event nua_i_state: 180 Ringing
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300158: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300190: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.300234: priv_nua_i_state_cb: call with handle 0x678b20 is proceeding
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:50.300266: tpsip_media_channel_change_call_state: setting call state 1 for peer 2
>tpsip/events-DEBUG: 03/09/2012 14:02:50.300323: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:51.252875: tpsip_connection_sofia_callback: event nua_r_register: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:51.253046: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:51.253187: tpsip_connection_sofia_callback: dispatching to target 0x653000 (handle 0x661630)
>tpsip/events-DEBUG: 03/09/2012 14:02:51.253398: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 03/09/2012 14:02:51.361263: outbound(0x661630): FAILED to validate <sip:83888@10.131.169.32:36105>
>tpsip/sofia-DEBUG: 03/09/2012 14:02:51.361409: outbound(0x661630): FAILED with 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:51.361566: tpsip_connection_sofia_callback: event nua_i_outbound: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:51.361685: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:51.361799: tpsip_connection_sofia_callback: dispatching to target 0x653000 (handle 0x661630)
>tpsip/events-DEBUG: 03/09/2012 14:02:51.361921: tpsip_connection_sofia_callback: event nua_i_outbound for target 0x653000 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:51.362044: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681004: tpsip_connection_sofia_callback: event nua_r_invite: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681174: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681346: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681510: tpsip_connection_sofia_callback: event nua_r_invite for target 0x64a970 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681648: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681803: tpsip_connection_sofia_callback: event nua_i_state: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:52.681941: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.682087: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.682277: priv_nua_i_state_cb: call with handle 0x678b20 is ready
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.682435: tpsip_media_session_set_remote_media: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.682590: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.682760: priv_update_remote_media: setting remote SDP for stream 0
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.682909: tpsip_media_stream_set_remote_media: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683063: push_remote_candidates: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683335: push_remote_candidates: remote RTP address=<10.131.232.71>, port=<27082>
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683455: push_remote_candidates: remote RTCP address=<10.131.232.71>, port=<27083>
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683515: push_remote_candidates: emitting SetRemoteCandidateList with L1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683655: push_remote_codecs: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683825: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683860: push_active_candidate_pair: emitting SetActiveCandidatePair for L1-L1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683912: tpsip_media_stream_set_direction: set direction 3, pending send flags 0
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.683971: priv_update_remote_hold: the session is remotely unheld
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.684003: priv_update_remote_media: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.684035: tpsip_media_channel_change_call_state: setting call state 0 for peer 2
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.684263: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.684299: tpsip_media_stream_set_sending: set sending to TRUE
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684346: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684381: tpsip_connection_sofia_callback: event nua_i_active: 200 Call active
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684417: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684451: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684494: tpsip_connection_sofia_callback: event nua_i_active for target 0x64a970 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:52.684530: tpsip_connection_sofia_callback: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.685400: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.685475: tpsip_media_stream_stream_state: changing stream state from 0 to 2
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.685631: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686260: tpsip_media_stream_supported_codecs: got codec intersection containing 2 codecs from stream-engine
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686305: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686342: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686605: priv_session_invite: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686645: priv_session_state_changed_cb: enter
>tpsip/events-DEBUG: 03/09/2012 14:02:52.686803: tpsip_connection_sofia_callback: event nua_i_state: 000 INVITE sent
>tpsip/events-DEBUG: 03/09/2012 14:02:52.686830: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.686851: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.686873: priv_nua_i_state_cb: call with handle 0x678b20 is calling
>tpsip/events-DEBUG: 03/09/2012 14:02:52.686893: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755143: tpsip_connection_sofia_callback: event nua_r_invite: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755350: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755491: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755537: tpsip_connection_sofia_callback: event nua_r_invite for target 0x64a970 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755567: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755598: tpsip_connection_sofia_callback: event nua_i_state: 200 OK
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755645: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.755676: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755716: priv_nua_i_state_cb: call with handle 0x678b20 is ready
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755759: tpsip_media_session_set_remote_media: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755799: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755836: priv_update_remote_media: setting remote SDP for stream 0
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755873: tpsip_media_stream_set_remote_media: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755913: tpsip_media_stream_set_remote_media: no media changes detected for the stream
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755949: priv_update_remote_hold: the session is remotely unheld
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.755984: priv_update_remote_media: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.756017: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 03/09/2012 14:02:52.756055: priv_session_state_changed_cb: enter
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756118: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756150: tpsip_connection_sofia_callback: event nua_i_active: 200 Call active
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756184: tpsip_connection_sofia_callback: connection 0x653000, refcount 2
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756222: tpsip_connection_sofia_callback: dispatching to target 0x64a970 (handle 0x678b20)
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756355: tpsip_connection_sofia_callback: event nua_i_active for target 0x64a970 was not consumed
>tpsip/events-DEBUG: 03/09/2012 14:02:52.756491: tpsip_connection_sofia_callback: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.593090: tpsip_media_session_terminate: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.593283: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.593521: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.593705: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.593847: tpsip_media_session_terminate: sending BYE
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594003: tpsip_media_session_change_state: destroying the NUA handle 0x678b20
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594167: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594393: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594451: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594485: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594556: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594595: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594633: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594674: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594734: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 03/09/2012 14:03:09.594777: tpsip_media_channel_finalize: exit

Actions: View

Attachments on bug 47146: 58232

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.