Attachment 38598 Details for Bug 29785 – Sofiasip debug output from recipient

Sofiasip debug output from recipient

recipient.debug.txt (text/plain), 15.09 KB, created by Chris Vine on 2010-09-10 02:36:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chris Vine

Created: 2010-09-10 02:36:22 UTC

Size: 15.09 KB

patch

obsolete

>tpsip/events-DEBUG: 10/09/10 10:19:18.511493: tpsip_connection_sofia_callback: event nua_i_invite: 100 Trying
>tpsip/events-DEBUG: 10/09/10 10:19:18.512047: tpsip_connection_sofia_callback: connection 0x8099930, refcount 1
>tpsip/events-DEBUG: 10/09/10 10:19:18.512562: tpsip_connection_sofia_callback: dispatching to connection 0x8099930 (unbound handle 0x809d800)
>tpsip/connection-DEBUG: 10/09/10 10:19:18.515104: tpsip_nua_i_invite_cb: Got incoming invite from <sip:cvine@sip.antisip.com>
>tpsip/connection-DEBUG: 10/09/10 10:19:18.515705: new_media_channel: channel object path /org/freedesktop/Telepathy/Connection/sofiasip/sip/vine_2echris_40sip_2eantisip_2ecom/MediaChannel1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.516891: tpsip_media_channel_constructed: registering object to dbus path=/org/freedesktop/Telepathy/Connection/sofiasip/sip/vine_2echris_40sip_2eantisip_2ecom/MediaChannel1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.522928: priv_create_session: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.523623: priv_create_session: allocating session, peer=2
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.531196: priv_create_session: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.531800: priv_session_state_changed_cb: enter
>tpsip/events-DEBUG: 10/09/10 10:19:18.535144: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:18.535680: tpsip_connection_sofia_callback: event nua_i_state: 100 Trying
>tpsip/events-DEBUG: 10/09/10 10:19:18.536191: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:18.536699: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.537241: tpsip_media_session_set_remote_media: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.537837: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.547734: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.548270: priv_update_remote_media: setting remote SDP for stream 0
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.548793: tpsip_media_stream_set_remote_media: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.549352: push_remote_candidates: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.549873: push_remote_candidates: the stream engine is not ready, SetRemoteCandidateList is pending
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.558551: push_remote_codecs: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.559088: push_remote_codecs: the stream engine is not ready, SetRemoteCodecs is pending
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.559597: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.560159: tpsip_media_session_add_stream: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.561938: tpsip_media_session_add_stream: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.562508: priv_update_remote_media: setting remote SDP for stream 1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.563034: tpsip_media_stream_set_remote_media: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.563559: push_remote_candidates: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.564075: push_remote_candidates: the stream engine is not ready, SetRemoteCandidateList is pending
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.564584: push_remote_codecs: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.565097: push_remote_codecs: the stream engine is not ready, SetRemoteCodecs is pending
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.565613: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.573954: priv_update_remote_hold: the session is remotely unheld
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.574587: priv_update_remote_media: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.575105: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.575625: priv_nua_i_state_cb: call with handle 0x809d800 is received
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.576139: priv_initial_media_properties_from_sdp: has initial audio
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.576646: priv_initial_media_properties_from_sdp: has initial video
>tpsip/events-DEBUG: 10/09/10 10:19:18.580682: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:18.587630: tpsip_connection_sofia_callback: event nua_i_state: 180 Ringing
>tpsip/events-DEBUG: 10/09/10 10:19:18.588187: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:18.588691: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/media-channel-DEBUG: 10/09/10 10:19:18.589257: priv_nua_i_state_cb: call with handle 0x809d800 is early
>tpsip/events-DEBUG: 10/09/10 10:19:18.589776: tpsip_connection_sofia_callback: exit
>tpsip/connection-DEBUG: 10/09/10 10:19:18.619952: conn_get_alias: handle 2 got alias cvine@sip.antisip.com
>tpsip/sofia-DEBUG: 10/09/10 10:19:27.110658: nta_agent: tport: Bad message
>tpsip/connection-DEBUG: 10/09/10 10:19:27.703963: conn_get_alias: handle 1 got alias vine.chris@sip.antisip.com
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.791929: _tpsip_media_channel_add_member: mixin->self_handle=1, handle=1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.792237: _tpsip_media_channel_add_member: accepting an incoming invite
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.792514: tpsip_media_stream_apply_pending_direction: set direction 3, pending send flags 0
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.793001: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.793272: tpsip_media_stream_set_sending: set sending to TRUE
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.793550: tpsip_media_stream_apply_pending_direction: set direction 3, pending send flags 0
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.794073: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.794358: tpsip_media_stream_set_sending: set sending to TRUE
>tpsip/media-channel-DEBUG: 10/09/10 10:19:27.794636: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.834536: tpsip_media_channel_get_session_handlers: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.843674: tpsip_media_session_ready: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.932363: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.933017: push_remote_candidates: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.933357: push_remote_candidates: remote RTP address=<91.121.81.212>, port=<45366>
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.933702: push_remote_candidates: remote RTCP address=<91.121.81.212>, port=<45367>
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.936222: push_remote_candidates: emitting SetRemoteCandidateList with L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.937222: push_remote_codecs: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:28.941698: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.118457: tpsip_media_stream_ready: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.119036: push_remote_candidates: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.119383: push_remote_candidates: remote RTP address=<91.121.81.212>, port=<45368>
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.119766: push_remote_candidates: remote RTCP address=<91.121.81.212>, port=<45369>
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.120223: push_remote_candidates: emitting SetRemoteCandidateList with L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.121117: push_remote_codecs: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.122432: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.143795: tpsip_media_stream_supported_codecs: got codec intersection containing 7 codecs from stream-engine
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.144139: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.155819: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.156944: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.157218: priv_request_response_step: there are local streams not ready, postponed
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.157481: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.157744: push_active_candidate_pair: emitting SetActiveCandidatePair for L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.168641: tpsip_media_stream_native_candidates_prepared: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.169272: priv_stream_ready_cb: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.169531: push_active_candidate_pair: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.169771: push_active_candidate_pair: emitting SetActiveCandidatePair for L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.176867: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.180526: tpsip_media_stream_stream_state: changing stream state from 0 to 2
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.185514: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.198489: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.199392: tpsip_media_stream_stream_state: changing stream state from 0 to 2
>tpsip/media-channel-DEBUG: 10/09/10 10:19:29.205734: tpsip_media_stream_new_active_candidate_pair: stream engine reported new active candidate pair L1-L1
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.196542: tpsip_media_stream_supported_codecs: got codec intersection containing 4 codecs from stream-engine
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.196794: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.197041: tpsip_media_stream_set_playing: set playing to TRUE
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.197310: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.197495: tpsip_media_stream_apply_pending_direction: remote end ready to receive
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.197688: priv_session_state_changed_cb: enter
>tpsip/events-DEBUG: 10/09/10 10:19:38.201617: tpsip_connection_sofia_callback: event nua_i_state: 200 OK
>tpsip/events-DEBUG: 10/09/10 10:19:38.201976: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.202258: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.202568: priv_nua_i_state_cb: call with handle 0x809d800 is completed
>tpsip/events-DEBUG: 10/09/10 10:19:38.202851: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:38.325475: tpsip_connection_sofia_callback: event nua_i_ack: 200 OK
>tpsip/events-DEBUG: 10/09/10 10:19:38.325870: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.326173: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/events-DEBUG: 10/09/10 10:19:38.326508: tpsip_connection_sofia_callback: event nua_i_ack for target 0x80aa168 was not consumed
>tpsip/events-DEBUG: 10/09/10 10:19:38.326864: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:38.327341: tpsip_connection_sofia_callback: event nua_i_state: 200 OK
>tpsip/events-DEBUG: 10/09/10 10:19:38.327925: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.328438: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.329018: priv_nua_i_state_cb: call with handle 0x809d800 is ready
>tpsip/events-DEBUG: 10/09/10 10:19:38.329500: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:38.329833: tpsip_connection_sofia_callback: event nua_i_active: 200 Call active
>tpsip/events-DEBUG: 10/09/10 10:19:38.330081: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.330317: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/events-DEBUG: 10/09/10 10:19:38.330574: tpsip_connection_sofia_callback: event nua_i_active for target 0x80aa168 was not consumed
>tpsip/events-DEBUG: 10/09/10 10:19:38.330815: tpsip_connection_sofia_callback: exit
>tpsip/sofia-DEBUG: 10/09/10 10:19:38.793767: nua(0x809d800): strange ACK from <sip:cvine@sip.antisip.com>
>tpsip/events-DEBUG: 10/09/10 10:19:38.865313: tpsip_connection_sofia_callback: event nua_i_bye: 200 Session Terminated
>tpsip/events-DEBUG: 10/09/10 10:19:38.865652: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.865904: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/events-DEBUG: 10/09/10 10:19:38.867214: tpsip_connection_sofia_callback: exit
>tpsip/events-DEBUG: 10/09/10 10:19:38.867469: tpsip_connection_sofia_callback: event nua_i_state: 200 Session Terminated
>tpsip/events-DEBUG: 10/09/10 10:19:38.867703: tpsip_connection_sofia_callback: connection 0x8099930, refcount 2
>tpsip/events-DEBUG: 10/09/10 10:19:38.867930: tpsip_connection_sofia_callback: dispatching to target 0x80aa168 (handle 0x809d800)
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.868200: priv_nua_i_state_cb: call with handle 0x809d800 is terminated
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.868525: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.868891: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.869213: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.869503: priv_stream_close_cb: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.875956: tpsip_media_stream_dispose: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.877006: tpsip_media_stream_finalize: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.877991: tpsip_media_session_change_state: destroying the NUA handle 0x809d800
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.878899: priv_session_state_changed_cb: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.894953: priv_destroy_session: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.895828: priv_destroy_session: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.896507: tpsip_media_channel_close: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.901416: tpsip_media_session_dispose: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.901751: tpsip_media_session_dispose: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.902240: tpsip_media_session_finalize: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.902489: tpsip_media_channel_dispose: enter
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.902826: tpsip_media_channel_dispose: exit
>tpsip/media-channel-DEBUG: 10/09/10 10:19:38.903150: tpsip_media_channel_finalize: exit
>tpsip/events-DEBUG: 10/09/10 10:19:38.903431: tpsip_connection_sofia_callback: exit

Actions: View

Attachments on bug 29785: 38128 | 38129 | 38597 | 38598

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.