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