Bug 40323

Summary: Can't establish audio/video stream
Product: Telepathy Reporter: Manuel.Bilderbeek
Component: gabbleAssignee: Telepathy bugs list <telepathy-bugs>
Status: RESOLVED MOVED QA Contact: Telepathy bugs list <telepathy-bugs>
Severity: normal    
Priority: medium CC: adessobastadavvero, olivier.crete, omrehman
Version: 0.13   
Hardware: x86-64 (AMD64)   
OS: Linux (All)   
Whiteboard:
i915 platform: i915 features:
Attachments: Messages in call window

Description Manuel.Bilderbeek 2011-08-23 13:12:05 UTC
Created attachment 50508 [details]
Messages in call window

Hi,

When trying to make a call via Gtalk, I got the messages from the attachment. This is what the Debug log says:

empathy/Voip-DEBUG: 08/23/2011 21:50:47.880511: empathy_tp_call_accept_incoming_call: Accepting incoming call
empathy/Voip-DEBUG: 08/23/2011 21:50:47.883272: enable_camera: Enable camera
empathy/Voip-DEBUG: 08/23/2011 21:50:47.883367: display_video_preview: Show video preview
empathy/Voip-DEBUG: 08/23/2011 21:50:47.883441: empathy_call_window_setup_video_preview: Create video preview
empathy/Voip-DEBUG: 08/23/2011 21:50:47.954111: tp_call_stream_direction_changed_cb: Stream direction changed - stream: 1, direction: 3
empathy/Voip-DEBUG: 08/23/2011 21:50:47.954196: tp_call_stream_direction_changed_cb: Stream direction changed - stream: 2, direction: 3
tp-fs-DEBUG: 08/23/2011 21:50:48.3454: GetSessionHandlers replied: 
tp-fs-DEBUG: 08/23/2011 21:50:48.3474:   - session /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel8
tp-fs-DEBUG: 08/23/2011 21:50:48.3495:     type rtp
tp-fs-DEBUG: 08/23/2011 21:50:48.3515: adding session handler /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel8, type rtp
tp-fs-DEBUG: 08/23/2011 21:50:48.3791: calling MediaSessionHandler::Ready
tp-fs-DEBUG: 08/23/2011 21:50:48.4317: New stream, stream_id=1, media_type=0, direction=3
tp-fs-DEBUG: 08/23/2011 21:50:48.4971: New stream, stream_id=2, media_type=1, direction=3
tp-fs-DEBUG: 08/23/2011 21:50:48.6896: stream 1 0x138e510 (audio) get_all_properties_cb: Adding STUN server 209.85.147.126:19302
tp-fs-DEBUG: 08/23/2011 21:50:48.6930: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (udp) 209.85.229.126:19295 cABsIF2VHEfLPaUd:U8CPbVsYqFAIZqpN 1
tp-fs-DEBUG: 08/23/2011 21:50:48.6961: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (tcp) 209.85.229.126:19294 cABsIF2VHEfLPaUd:U8CPbVsYqFAIZqpN 1
tp-fs-DEBUG: 08/23/2011 21:50:48.6989: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (tls) 209.85.229.126:443 cABsIF2VHEfLPaUd:U8CPbVsYqFAIZqpN 1
tp-fs-DEBUG: 08/23/2011 21:50:48.7019: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (udp) 209.85.229.126:19295 z1CtXecD6duD7ALr:b1kBKckEKxPR1r8B 2
tp-fs-DEBUG: 08/23/2011 21:50:48.7047: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (tcp) 209.85.229.126:19294 z1CtXecD6duD7ALr:b1kBKckEKxPR1r8B 2
tp-fs-DEBUG: 08/23/2011 21:50:48.7076: stream 1 0x138e510 (audio) get_all_properties_cb: Adding relay (tls) 209.85.229.126:443 z1CtXecD6duD7ALr:b1kBKckEKxPR1r8B 2
tp-fs-DEBUG: 08/23/2011 21:50:48.28023: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: called (send_local:1 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.28083: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 103: audio SPEEX clock:8000 channels:1
tp-fs-DEBUG: 08/23/2011 21:50:48.28104: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 104: audio SPEEX clock:16000 channels:1
tp-fs-DEBUG: 08/23/2011 21:50:48.28125: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 99: audio AMR clock:8000 channels:1
tp-fs-DEBUG: 08/23/2011 21:50:48.28153: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 96: audio SIREN clock:16000 channels:0 bitrate=16000
tp-fs-DEBUG: 08/23/2011 21:50:48.28179: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 0: audio PCMU clock:8000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.28206: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 8: audio PCMA clock:8000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.28228: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 3: audio GSM clock:8000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.28251: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 100: audio telephone-event clock:16000 channels:0 events=0-15
tp-fs-DEBUG: 08/23/2011 21:50:48.28275: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 101: audio telephone-event clock:8000 channels:0 events=0-15
tp-fs-DEBUG: 08/23/2011 21:50:48.28322: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 103: audio SPEEX clock:8000 channels:1 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28357: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 104: audio SPEEX clock:16000 channels:1 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28393: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 99: audio AMR clock:8000 channels:1 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28433: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 96: audio SIREN clock:16000 channels:0 params:0x1759fa0
tp-fs-DEBUG: 08/23/2011 21:50:48.28469: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 0: audio PCMU clock:8000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28503: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 8: audio PCMA clock:8000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28538: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 3: audio GSM clock:8000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.28577: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 100: audio telephone-event clock:16000 channels:0 params:0x2d45040
tp-fs-DEBUG: 08/23/2011 21:50:48.28614: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 101: audio telephone-event clock:8000 channels:0 params:0x2d45180
tp-fs-DEBUG: 08/23/2011 21:50:48.28636: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: calling MediaStreamHandler::Ready
tp-fs-DEBUG: 08/23/2011 21:50:48.30409: stream 2 0x138ed60 (video) get_all_properties_cb: Adding STUN server 209.85.147.126:19302
tp-fs-DEBUG: 08/23/2011 21:50:48.30440: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (udp) 209.85.147.126:19295 V6qcx3PkR2yrnPQ4:AE5QWa0V3fEpFvGo 1
tp-fs-DEBUG: 08/23/2011 21:50:48.30467: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (tcp) 209.85.147.126:19294 V6qcx3PkR2yrnPQ4:AE5QWa0V3fEpFvGo 1
tp-fs-DEBUG: 08/23/2011 21:50:48.30492: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (tls) 209.85.147.126:443 V6qcx3PkR2yrnPQ4:AE5QWa0V3fEpFvGo 1
tp-fs-DEBUG: 08/23/2011 21:50:48.30517: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (udp) 209.85.229.126:19295 GaGO82VRDrQ2fmMA:ZZbgpauCIKY4tj9p 2
tp-fs-DEBUG: 08/23/2011 21:50:48.30546: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (tcp) 209.85.229.126:19294 GaGO82VRDrQ2fmMA:ZZbgpauCIKY4tj9p 2
tp-fs-DEBUG: 08/23/2011 21:50:48.30572: stream 2 0x138ed60 (video) get_all_properties_cb: Adding relay (tls) 209.85.229.126:443 GaGO82VRDrQ2fmMA:ZZbgpauCIKY4tj9p 2
tp-fs-DEBUG: 08/23/2011 21:50:48.44804: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: called (send_local:1 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.44851: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 97: video H264 clock:90000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.44867: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 34: video H263 clock:90000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.44886: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 96: video THEORA clock:90000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.44907: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 98: video H263-1998 clock:90000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.44929: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 100: video VP8-DRAFT-0-3-2 clock:90000 channels:0
tp-fs-DEBUG: 08/23/2011 21:50:48.44969: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 97: video H264 clock:90000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.44996: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 34: video H263 clock:90000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.45023: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 96: video THEORA clock:90000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.45051: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 98: video H263-1998 clock:90000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.45078: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 100: video VP8-DRAFT-0-3-2 clock:90000 channels:0 params:(nil)
tp-fs-DEBUG: 08/23/2011 21:50:48.45097: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: calling MediaStreamHandler::Ready
tp-fs-DEBUG: 08/23/2011 21:50:48.48207: stream 1 0x138e510 (audio) set_remote_codecs: called
tp-fs-DEBUG: 08/23/2011 21:50:48.48234: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec ISAC [103]
tp-fs-DEBUG: 08/23/2011 21:50:48.48249: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec ISAC [104]
tp-fs-DEBUG: 08/23/2011 21:50:48.48270: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec ISACLC [119]
tp-fs-DEBUG: 08/23/2011 21:50:48.48285: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec speex [99]
tp-fs-DEBUG: 08/23/2011 21:50:48.48306: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec IPCMWB [97]
tp-fs-DEBUG: 08/23/2011 21:50:48.48321: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec G722 [9]
tp-fs-DEBUG: 08/23/2011 21:50:48.48342: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec iLBC [102]
tp-fs-DEBUG: 08/23/2011 21:50:48.48357: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec speex [98]
tp-fs-DEBUG: 08/23/2011 21:50:48.48377: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec GSM [3]
tp-fs-DEBUG: 08/23/2011 21:50:48.48393: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec EG711U [100]
tp-fs-DEBUG: 08/23/2011 21:50:48.48413: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec EG711A [101]
tp-fs-DEBUG: 08/23/2011 21:50:48.48429: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec PCMU [0]
tp-fs-DEBUG: 08/23/2011 21:50:48.48444: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec PCMA [8]
tp-fs-DEBUG: 08/23/2011 21:50:48.48460: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec red [117]
tp-fs-DEBUG: 08/23/2011 21:50:48.48474: stream 1 0x138e510 (audio) set_remote_codecs: adding remote codec telephone-event [106]
tp-fs-DEBUG: 08/23/2011 21:50:48.48989: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:1)
tp-fs-DEBUG: 08/23/2011 21:50:48.49012: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 99: audio speex clock:16000 channels:1 bitrate=22000
tp-fs-DEBUG: 08/23/2011 21:50:48.49027: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 98: audio speex clock:8000 channels:1 bitrate=11000
tp-fs-DEBUG: 08/23/2011 21:50:48.49041: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 3: audio GSM clock:8000 channels:0 bitrate=13200
tp-fs-DEBUG: 08/23/2011 21:50:48.49056: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 0: audio PCMU clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.49068: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 8: audio PCMA clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.49082: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 106: audio telephone-event clock:8000 channels:0 events=0-15
tp-fs-DEBUG: 08/23/2011 21:50:48.49113: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 99: audio speex clock:16000 channels:1 params:0x1730760
tp-fs-DEBUG: 08/23/2011 21:50:48.49139: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 98: audio speex clock:8000 channels:1 params:0x17305a0
tp-fs-DEBUG: 08/23/2011 21:50:48.49165: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 3: audio GSM clock:8000 channels:0 params:0x2049b00
tp-fs-DEBUG: 08/23/2011 21:50:48.49190: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 0: audio PCMU clock:8000 channels:0 params:0x7f9314078040
tp-fs-DEBUG: 08/23/2011 21:50:48.49217: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 8: audio PCMA clock:8000 channels:0 params:0x1e2b480
tp-fs-DEBUG: 08/23/2011 21:50:48.49243: stream 1 0x138e510 (audio) fs_codecs_to_tp: adding codec 106: audio telephone-event clock:8000 channels:0 params:0x1e2b160
tp-fs-DEBUG: 08/23/2011 21:50:48.49257: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: calling MediaStreamHandler::SupportedCodecs
tp-fs-DEBUG: 08/23/2011 21:50:48.52110: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R1
tp-fs-DEBUG: 08/23/2011 21:50:48.52272: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R2
tp-fs-DEBUG: 08/23/2011 21:50:48.52411: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R3
tp-fs-DEBUG: 08/23/2011 21:50:48.52546: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R4
tp-fs-DEBUG: 08/23/2011 21:50:48.52644: stream 1 0x138e510 (audio) set_stream_playing: 0
tp-fs-DEBUG: 08/23/2011 21:50:48.52743: stream 1 0x138e510 (audio) set_stream_sending: 0
tp-fs-DEBUG: 08/23/2011 21:50:48.53152: stream 2 0x138ed60 (video) set_remote_codecs: called
tp-fs-DEBUG: 08/23/2011 21:50:48.53173: stream 2 0x138ed60 (video) set_remote_codecs: adding remote codec H264-SVC [99]
tp-fs-DEBUG: 08/23/2011 21:50:48.53193: stream 2 0x138ed60 (video) set_remote_codecs: adding remote codec H264-SVC-draft-02 [96]
tp-fs-DEBUG: 08/23/2011 21:50:48.53211: stream 2 0x138ed60 (video) set_remote_codecs: adding remote codec H264 [97]
tp-fs-DEBUG: 08/23/2011 21:50:48.53231: stream 2 0x138ed60 (video) set_remote_codecs: adding remote codec H263 [98]
tp-fs-DEBUG: 08/23/2011 21:50:48.53616: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:1)
tp-fs-DEBUG: 08/23/2011 21:50:48.53647: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 97: video H264 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.53668: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 98: video H263 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.53725: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 97: video H264 clock:90000 channels:0 params:0x7f931409fe00
tp-fs-DEBUG: 08/23/2011 21:50:48.53765: stream 2 0x138ed60 (video) fs_codecs_to_tp: adding codec 98: video H263 clock:90000 channels:0 params:0x7f93140818c0
tp-fs-DEBUG: 08/23/2011 21:50:48.53786: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: calling MediaStreamHandler::SupportedCodecs
tp-fs-DEBUG: 08/23/2011 21:50:48.54768: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R1
tp-fs-DEBUG: 08/23/2011 21:50:48.54986: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R2
tp-fs-DEBUG: 08/23/2011 21:50:48.55183: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R3
tp-fs-DEBUG: 08/23/2011 21:50:48.55320: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R4
tp-fs-DEBUG: 08/23/2011 21:50:48.55418: stream 2 0x138ed60 (video) set_stream_playing: 0
tp-fs-DEBUG: 08/23/2011 21:50:48.55510: stream 2 0x138ed60 (video) set_stream_sending: 0
tp-fs-DEBUG: 08/23/2011 21:50:48.55687: stream 1 0x138e510 (audio) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.55702: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.55738: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 99: audio speex clock:16000 channels:1 bitrate=22000
tp-fs-DEBUG: 08/23/2011 21:50:48.55757: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 98: audio speex clock:8000 channels:1 bitrate=11000
tp-fs-DEBUG: 08/23/2011 21:50:48.55775: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 3: audio GSM clock:8000 channels:0 bitrate=13200
tp-fs-DEBUG: 08/23/2011 21:50:48.55793: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 0: audio PCMU clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.55810: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 8: audio PCMA clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.55829: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 106: audio telephone-event clock:8000 channels:0 events=0-15
tp-fs-DEBUG: 08/23/2011 21:50:48.56596: stream 1 0x138e510 (audio) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.56612: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.56646: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 99: audio speex clock:16000 channels:1 bitrate=22000
tp-fs-DEBUG: 08/23/2011 21:50:48.56663: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 98: audio speex clock:8000 channels:1 bitrate=11000
tp-fs-DEBUG: 08/23/2011 21:50:48.56682: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 3: audio GSM clock:8000 channels:0 bitrate=13200
tp-fs-DEBUG: 08/23/2011 21:50:48.56699: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 0: audio PCMU clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.56716: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 8: audio PCMA clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.56735: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 106: audio telephone-event clock:8000 channels:0 events=0-15
empathy/Voip-DEBUG: 08/23/2011 21:50:48.57105: tp_call_stream_state_changed_cb: Stream state changed - stream id: 1, state state: 1
tp-fs-DEBUG: 08/23/2011 21:50:48.57542: stream 2 0x138ed60 (video) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.57557: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.57589: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 97: video H264 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.57609: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 98: video H263 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.58248: stream 2 0x138ed60 (video) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.58265: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.58300: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 97: video H264 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.58320: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 98: video H263 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.58521: stream 1 0x138e510 (audio) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.58536: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.58568: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 99: audio speex clock:16000 channels:1 bitrate=22000
tp-fs-DEBUG: 08/23/2011 21:50:48.58587: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 98: audio speex clock:8000 channels:1 bitrate=11000
tp-fs-DEBUG: 08/23/2011 21:50:48.58604: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 3: audio GSM clock:8000 channels:0 bitrate=13200
tp-fs-DEBUG: 08/23/2011 21:50:48.58623: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 0: audio PCMU clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.58640: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 8: audio PCMA clock:8000 channels:0 bitrate=64000
tp-fs-DEBUG: 08/23/2011 21:50:48.58659: stream 1 0x138e510 (audio) _tf_stream_try_sending_codecs: 106: audio telephone-event clock:8000 channels:0 events=0-15
empathy/Voip-DEBUG: 08/23/2011 21:50:48.66063: tp_call_stream_state_changed_cb: Stream state changed - stream id: 2, state state: 1
tp-fs-DEBUG: 08/23/2011 21:50:48.59019: stream 2 0x138ed60 (video) _tf_stream_bus_message: Codecs changed
tp-fs-DEBUG: 08/23/2011 21:50:48.65897: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: called (send_local:0 send_supported:0)
tp-fs-DEBUG: 08/23/2011 21:50:48.66004: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 97: video H264 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.66032: stream 2 0x138ed60 (video) _tf_stream_try_sending_codecs: 98: video H263 clock:90000 channels:0 width=320 height=200 framerate=30
tp-fs-DEBUG: 08/23/2011 21:50:48.78253: stream 1 0x138e510 (audio) _tf_stream_bus_message: Send codec changed: 99: audio speex clock:16000 channels:1 params:0x13a74c0
empathy/Other-DEBUG: 08/23/2011 21:50:48.173491: main_window_flash_stop: Stop flashing
tp-fs-DEBUG: 08/23/2011 21:50:48.209105: stream 2 0x138ed60 (video) _tf_stream_bus_message: Send codec changed: 97: video H264 clock:90000 channels:0 params:0x182c380
tp-fs-DEBUG: 08/23/2011 21:50:48.240375: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.240685: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.240859: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.241003: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.241168: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.241337: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.242464: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.242605: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.242743: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.242882: stream 1 0x138e510 (audio) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.243016: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: called
tp-fs-DEBUG: 08/23/2011 21:50:48.243047: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.243807: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.244329: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.244870: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.245398: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.245903: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.246304: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '85.151.238.68 port = 38769 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.246331: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '85.151.238.68 port = 37077 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.246913: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '192.168.1.10 port = 38769 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.246942: stream 1 0x138e510 (audio) cb_fs_local_candidates_prepared: ip = '192.168.1.10 port = 37077 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.253261: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.253432: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.253590: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.253765: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.253907: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254054: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254200: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254347: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254492: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254637: stream 2 0x138ed60 (video) cb_fs_new_local_candidate: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254775: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: called
tp-fs-DEBUG: 08/23/2011 21:50:48.254832: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.255050: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.147.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.255223: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.255410: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.147.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.255588: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.229.126 port = 19295 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.255695: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '85.151.238.68 port = 54291 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.255723: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '85.151.238.68 port = 42623 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.255819: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '209.85.147.126 port = 19295 component = 1'
tp-fs-DEBUG: 08/23/2011 21:50:48.255927: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '192.168.1.10 port = 54291 component = 2'
tp-fs-DEBUG: 08/23/2011 21:50:48.255954: stream 2 0x138ed60 (video) cb_fs_local_candidates_prepared: ip = '192.168.1.10 port = 42623 component = 1'
empathy/Voip-DEBUG: 08/23/2011 21:50:48.257749: tp_call_stream_state_changed_cb: Stream state changed - stream id: 1, state state: 1
empathy/Voip-DEBUG: 08/23/2011 21:50:48.257880: tp_call_stream_state_changed_cb: Stream state changed - stream id: 2, state state: 1
tp-fs-DEBUG: 08/23/2011 21:50:48.779017: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:2 local: 5 209.85.229.126:19295  remote: 1 76.175.190.17:56644
tp-fs-DEBUG: 08/23/2011 21:50:48.780139: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:1 local: 4 209.85.229.126:19295  remote: 1 76.175.190.17:56646
empathy/Voip-DEBUG: 08/23/2011 21:50:48.780911: tp_call_stream_state_changed_cb: Stream state changed - stream id: 1, state state: 1
tp-fs-DEBUG: 08/23/2011 21:50:48.900957: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:2 local: 3 85.151.238.68:38769  remote: 1 76.175.190.17:56644
tp-fs-DEBUG: 08/23/2011 21:50:48.931674: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:1 local: 3 85.151.238.68:37077  remote: 1 76.175.190.17:56646
tp-fs-DEBUG: 08/23/2011 21:50:49.150024: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:1 local: 7 209.85.147.126:19295  remote: 1 76.175.190.17:56650
empathy/Voip-DEBUG: 08/23/2011 21:50:49.150521: tp_call_stream_state_changed_cb: Stream state changed - stream id: 2, state state: 1
tp-fs-DEBUG: 08/23/2011 21:50:49.155036: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:2 local: 8 209.85.229.126:19295  remote: 1 76.175.190.17:56648
tp-fs-DEBUG: 08/23/2011 21:50:49.166554: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:1 local: 3 85.151.238.68:37077  remote: R4 76.175.190.17:56647
tp-fs-DEBUG: 08/23/2011 21:50:49.207647: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:2 local: 8 209.85.229.126:19295  remote: R4 76.175.190.17:56649
tp-fs-DEBUG: 08/23/2011 21:50:49.208205: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:1 local: 7 209.85.147.126:19295  remote: R3 76.175.190.17:56651
tp-fs-DEBUG: 08/23/2011 21:50:49.216422: stream 1 0x138e510 (audio) cb_fs_new_active_candidate_pair: called: c:2 local: 3 85.151.238.68:38769  remote: R3 76.175.190.17:56645
tp-fs-DEBUG: 08/23/2011 21:50:49.217005: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:1 local: 6 85.151.238.68:42623  remote: R3 76.175.190.17:56651
tp-fs-DEBUG: 08/23/2011 21:50:49.256124: stream 2 0x138ed60 (video) cb_fs_new_active_candidate_pair: called: c:2 local: 6 85.151.238.68:54291  remote: R4 76.175.190.17:56649
tp-fs-DEBUG: 08/23/2011 21:50:49.658866: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R5
tp-fs-DEBUG: 08/23/2011 21:50:49.777667: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R6
tp-fs-DEBUG: 08/23/2011 21:50:49.778266: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R5
tp-fs-DEBUG: 08/23/2011 21:50:49.780143: stream 2 0x138ed60 (video) add_remote_candidate: adding remote candidate R6
tp-fs-DEBUG: 08/23/2011 21:50:50.631710: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R7
tp-fs-DEBUG: 08/23/2011 21:50:50.632096: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R8
tp-fs-MESSAGE: 08/23/2011 21:50:50.632116: tf_stream_error: stream error errorno=6 error=Invalid remote candidates passed
tp-fs-DEBUG: 08/23/2011 21:50:50.632306: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R9
tp-fs-DEBUG: 08/23/2011 21:50:50.632632: stream 1 0x138e510 (audio) add_remote_candidate: adding remote candidate R10
tp-fs-MESSAGE: 08/23/2011 21:50:50.632644: tf_stream_error: stream error errorno=6 error=Invalid remote candidates passed
empathy/Voip-DEBUG: 08/23/2011 21:50:50.633600: tp_call_stream_error_cb: Stream error on stream 1: Invalid remote candidates passed (code: 6)
empathy/Voip-DEBUG: 08/23/2011 21:50:50.636856: tp_call_stream_error_cb: Stream error on stream 1: Invalid remote candidates passed (code: 0)
tp-fs-DEBUG: 08/23/2011 21:50:50.639566: stream 1 0x138e510 (audio) stream_close: close requested by connection manager
empathy/Voip-DEBUG: 08/23/2011 21:50:50.642982: tp_call_stream_removed_cb: Stream removed - stream id: 1
empathy/Voip-DEBUG: 08/23/2011 21:50:50.643199: tp_call_stream_error_cb: Stream error on stream 2: Invalid remote candidates passed (code: 0)
tp-fs-DEBUG: 08/23/2011 21:50:50.645956: stream 2 0x138ed60 (video) stream_close: close requested by connection manager
empathy/Voip-DEBUG: 08/23/2011 21:50:50.735918: tp_call_stream_removed_cb: Stream removed - stream id: 2
empathy/Dispatcher-DEBUG: 08/23/2011 21:50:50.736018: dispatcher_channel_invalidated_cb: Channel /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel8 invalidated
empathy/Voip-DEBUG: 08/23/2011 21:50:50.736052: tp_call_channel_invalidated_cb: Channel invalidated: Invalid remote candidates passed
empathy/Other-DEBUG: 08/23/2011 21:50:50.790323: instance_destroyed_cb: instance for 0x129f080 destroyed; cleaning up
empathy/Voip-DEBUG: 08/23/2011 21:50:50.790841: disable_camera: Disable camera

I hope this helps....

If there's any extra info I should provide, please let me know.
Comment 1 Olivier Crête 2011-11-01 08:58:21 UTC
Upgrade to the newest farsight2 release.. The problem here is that Google sometimes passes candidates with port 0 and we reject them and kill the call.
Comment 2 Will Thompson 2011-11-01 09:08:34 UTC
*** Bug 40197 has been marked as a duplicate of this bug. ***
Comment 3 Manuel.Bilderbeek 2011-11-01 11:27:01 UTC
OK, I tried again (I have the latest farsight2 installed now) and now I get another issue: Could not link source. Logging:

mcd-DEBUG: 11/01/2011 19:23:08.374424: dispatcher_channel_request_acl_start: start /org/freedesktop/Telepathy/Account/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom1.org.freedesktop.Telepathy.Connection.Interface.Requests.CreateChannel acl (0xd46e40)
mcd-DEBUG: 11/01/2011 19:23:08.374430: dispatcher_channel_request_acl_success: complete acl (0xd46e40)
mcd-DEBUG: 11/01/2011 19:23:08.374464: _mcd_request_init: 0xde0390
mcd-DEBUG: 11/01/2011 19:23:08.374516: _mcd_request_new: 0xde0390 (for 0xd6f030)
mcd-DEBUG: 11/01/2011 19:23:08.374530: _mcd_channel_set_status: 0xdaab60, 1
mcd-DEBUG: 11/01/2011 19:23:08.374569: _mcd_request_predict_handler: Default handler org.freedesktop.Telepathy.Client.Empathy.AudioVideo for request /com/nokia/MissionControl/requests/r3 doesn't want AddRequest
mcd-DEBUG: 11/01/2011 19:23:08.374572: dispatcher_channel_request_acl_cleanup: cleanup acl (0xd46e40)
mcd-DEBUG: 11/01/2011 19:23:08.376121: ready_to_request_cb: Starting online request
mcd-DEBUG: 11/01/2011 19:23:08.376126: _mcd_account_online_request: connection status for gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom1 is 0
mcd-DEBUG: 11/01/2011 19:23:08.376127: _mcd_account_online_request: gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom1 is already connected
mcd-DEBUG: 11/01/2011 19:23:08.376127: online_request_cb: called
mcd-DEBUG: 11/01/2011 19:23:08.376132: _mcd_mission_set_parent: child = 0xdaab60, parent = 0xd690d0
mcd-DEBUG: 11/01/2011 19:23:08.376197: _mcd_channel_set_status: 0xdaab60, 2
mcd-DEBUG: 11/01/2011 19:23:08.464819: common_request_channel_cb: 0xdaab60, object /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel1
mcd-DEBUG: 11/01/2011 19:23:08.465290: _mcd_dispatcher_take_channels: requested channel 0xdaab60 (only): /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel1
mcd-DEBUG: 11/01/2011 19:23:08.465311: _mcd_dispatcher_take_channels: possible handler(s) found, dispatching 1 channels
mcd-DEBUG: 11/01/2011 19:23:08.465312: _mcd_channel_set_status: 0xdaab60, 3
mcd-DEBUG: 11/01/2011 19:23:08.465318: _mcd_dispatcher_enter_state_machine: CTXREF11 on 0xd53d60
mcd-DEBUG: 11/01/2011 19:23:08.465321: _mcd_dispatcher_enter_state_machine: new dispatcher context 0xd53d60 for requested channel 0xdaab60 (only): /org/freedesktop/Telepathy/Connection/gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom_2ff2cd6f74/MediaChannel1
mcd-DEBUG: 11/01/2011 19:23:08.465336: mcd_dispatch_operation_set_property: Extracted preferred handler: org.freedesktop.Telepathy.Client.Empathy.AudioVideo
mcd-DEBUG: 11/01/2011 19:23:08.465342: mcd_dispatch_operation_constructor: do10/0xdc7170: needs_approval=F
mcd-DEBUG: 11/01/2011 19:23:08.465347: _mcd_plugin_dispatch_operation_init: 0xd3f2c0
mcd-DEBUG: 11/01/2011 19:23:08.465349: _mcd_plugin_dispatch_operation_new: 0xd3f2c0 (for 0xdc7170)
mcd-DEBUG: 11/01/2011 19:23:08.465353: _mcd_dispatcher_enter_state_machine: entering state machine for context 0xd53d60
mcd-DEBUG: 11/01/2011 19:23:08.465356: mcd_dispatcher_context_ref: CTXREF01 on 0xd53d60 (ref = 1)
mcd-DEBUG: 11/01/2011 19:23:08.465358: _mcd_dispatch_operation_run_clients: do10 0xdc7170
mcd-DEBUG: 11/01/2011 19:23:08.465359: _mcd_dispatch_operation_handlers_can_bypass_observers: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassObservers=F
mcd-DEBUG: 11/01/2011 19:23:08.465362: _mcd_dispatch_operation_run_clients: Running observers
mcd-DEBUG: 11/01/2011 19:23:08.465414: _mcd_dispatch_operation_inc_observers_pending: 0 -> 1
mcd-DEBUG: 11/01/2011 19:23:08.465415: _mcd_dispatch_operation_run_observers: calling ObserveChannels on org.freedesktop.Telepathy.Client.Logger for CDO 0xdc7170
mcd-DEBUG: 11/01/2011 19:23:08.465637: _mcd_dispatch_operation_inc_observers_pending: 1 -> 2
mcd-DEBUG: 11/01/2011 19:23:08.465640: _mcd_dispatch_operation_run_observers: calling ObserveChannels on org.freedesktop.Telepathy.Client.Empathy.CallObserver for CDO 0xdc7170
mcd-DEBUG: 11/01/2011 19:23:08.465809: _mcd_dispatch_operation_run_clients: All necessary observers invoked
mcd-DEBUG: 11/01/2011 19:23:08.465811: _mcd_dispatch_operation_run_clients: Checking finished/locks
mcd-DEBUG: 11/01/2011 19:23:08.465812: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
mcd-DEBUG: 11/01/2011 19:23:08.465815: mcd_dispatcher_context_unref: CTXREF01 on 0xd53d60 (ref = 2)
mcd-DEBUG: 11/01/2011 19:23:08.465816: mcd_dispatcher_context_unref: CTXREF11 on 0xd53d60 (ref = 1)
mcd-DEBUG: 11/01/2011 19:23:08.465817: mcd_dispatcher_context_unref: freeing the context 0xd53d60
mcd-DEBUG: 11/01/2011 19:23:08.465940: on_channel_ready: channel 0xdaab60 is ready
mcd-DEBUG: 11/01/2011 19:23:08.467369: observe_channels_cb: success from /org/freedesktop/Telepathy/Client/Empathy/CallObserver
mcd-DEBUG: 11/01/2011 19:23:08.467370: _mcd_dispatch_operation_dec_observers_pending: 2 -> 1
mcd-DEBUG: 11/01/2011 19:23:08.467374: _mcd_dispatch_operation_handlers_can_bypass_approval: org.freedesktop.Telepathy.Client.Empathy.AudioVideo has BypassApproval=F
mcd-DEBUG: 11/01/2011 19:23:08.467525: observe_channels_cb: success from /org/freedesktop/Telepathy/Client/Logger
mcd-DEBUG: 11/01/2011 19:23:08.467526: _mcd_dispatch_operation_dec_observers_pending: 1 -> 0
mcd-DEBUG: 11/01/2011 19:23:08.467528: _mcd_plugin_dispatch_operation_observers_finished: 0xd3f2c0
mcd-DEBUG: 11/01/2011 19:23:08.467530: _mcd_dispatch_operation_check_client_locks: trying next handler
mcd-DEBUG: 11/01/2011 19:23:08.467531: _mcd_dispatch_operation_try_next_handler: Approved handler is org.freedesktop.Telepathy.Client.Empathy.AudioVideo (still exists: Y, already failed: N)
mcd-DEBUG: 11/01/2011 19:23:08.467535: mcd_dispatch_operation_try_handler: do10: channel ACL verification [1 channels]
mcd-DEBUG: 11/01/2011 19:23:08.467552: _mcd_client_proxy_handle_channels: calling HandleChannels on org.freedesktop.Telepathy.Client.Empathy.AudioVideo
mcd-DEBUG: 11/01/2011 19:23:08.467586: _mcd_channel_set_status: 0xdaab60, 4
mcd-DEBUG: 11/01/2011 19:23:08.507028: dbusprop_get_all: org.freedesktop.Telepathy.AccountManager
mcd-DEBUG: 11/01/2011 19:23:08.507034: get_valid_accounts: called
mcd-DEBUG: 11/01/2011 19:23:08.507045: get_invalid_accounts: called
mcd-DEBUG: 11/01/2011 19:23:08.507047: mcd_dbus_get_interfaces: called
mcd-DEBUG: 11/01/2011 19:23:08.510706: dbusprop_get_all: org.freedesktop.Telepathy.Account
mcd-DEBUG: 11/01/2011 19:23:08.510710: mcd_dbus_get_interfaces: called
mcd-DEBUG: 11/01/2011 19:23:08.510715: mcd_dbus_get_interfaces: skipping inactive optional iface org.freedesktop.Telepathy.Account.Interface.ExternalPasswordStorage.DRAFT
mcd-DEBUG: 11/01/2011 19:23:08.510734: _mcd_account_dup_parameters: called
mcd-DEBUG: 11/01/2011 19:23:08.510786: get_connect_automatically: called for gabble/jabber/ankemanuel_40gmail_2ecom1
mcd-DEBUG: 11/01/2011 19:23:08.511020: dbusprop_get_all: org.freedesktop.Telepathy.Account
mcd-DEBUG: 11/01/2011 19:23:08.511023: mcd_dbus_get_interfaces: called
mcd-DEBUG: 11/01/2011 19:23:08.511027: mcd_dbus_get_interfaces: skipping inactive optional iface org.freedesktop.Telepathy.Account.Interface.ExternalPasswordStorage.DRAFT
mcd-DEBUG: 11/01/2011 19:23:08.511039: _mcd_account_dup_parameters: called
mcd-DEBUG: 11/01/2011 19:23:08.511079: get_connect_automatically: called for gabble/jabber/manuel_2ebilderbeek_40gmail_2ecom1
mcd-DEBUG: 11/01/2011 19:23:08.512384: dbusprop_get_all: org.freedesktop.Telepathy.Account.Interface.Addressing
mcd-DEBUG: 11/01/2011 19:23:08.512537: dbusprop_get_all: org.freedesktop.Telepathy.Account.Interface.Addressing
mcd-DEBUG: 11/01/2011 19:23:08.515059: _mcd_channel_set_status: 0xdaab60, 5
mcd-DEBUG: 11/01/2011 19:23:08.515067: _mcd_request_set_success: Request succeeded
mcd-DEBUG: 11/01/2011 19:23:08.515197: _mcd_dispatch_operation_finish: Result: Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
mcd-DEBUG: 11/01/2011 19:23:08.515199: _mcd_dispatch_operation_finish: do10/0xdc7170 has finished
mcd-DEBUG: 11/01/2011 19:23:08.515201: mcd_dispatch_operation_actually_finish: do10/0xdc7170: finished
mcd-DEBUG: 11/01/2011 19:23:08.515206: _mcd_dispatch_operation_check_client_locks: waiting for handler_is_suitable or HandleChannels to return
mcd-DEBUG: 11/01/2011 19:23:08.515208: _mcd_dispatch_operation_check_client_locks: already finished (or finishing): Channel successfully handled by org.freedesktop.Telepathy.Client.Empathy.AudioVideo
mcd-DEBUG: 11/01/2011 19:23:08.515211: plugin_do_finalize: 0xd3f2c0
mcd-DEBUG: 11/01/2011 19:23:08.861757: on_members_changed: called (actor 1, reason 6, self_handle 1, conn_self_handle 1)
mcd-DEBUG: 11/01/2011 19:23:08.861761: on_members_changed: removed member 1
mcd-DEBUG: 11/01/2011 19:23:08.861852: proxy_destroyed: Channel proxy invalidated: tp_errors 3: Could not link source
mcd-DEBUG: 11/01/2011 19:23:08.861856: mcd_channel_abort: 0xdaab60
mcd-DEBUG: 11/01/2011 19:23:08.861859: _mcd_channel_set_status: 0xdaab60, 7
mcd-DEBUG: 11/01/2011 19:23:08.861871: _mcd_mission_set_parent: child = 0xdaab60, parent = (nil)
mcd-DEBUG: 11/01/2011 19:23:08.861877: _mcd_operation_remove_mission: removing mission: 0xdaab60
mcd-DEBUG: 11/01/2011 19:23:08.861880: _mcd_channel_dispose: 0xdaab60 (is disposed = 0)
mcd-DEBUG: 11/01/2011 19:23:08.861884: _mcd_mission_dispose: mission disposed 0xdaab60
mcd-DEBUG: 11/01/2011 19:23:08.861888: _mcd_request_dispose: 0xde0390
mcd-DEBUG: 11/01/2011 19:23:08.861893: _mcd_request_finalize: 0xde0390
mcd-DEBUG: 11/01/2011 19:23:08.861898: _mcd_mission_finalize: mission finalized 0xdaab60
Comment 4 Olivier Crête 2011-11-01 11:47:43 UTC
Do you have the log from empathy-av ? I assume you're using empathy ?
Comment 5 Manuel.Bilderbeek 2011-11-01 13:03:28 UTC
Yes, I've been running empathy and copied the log from empathy. So I'm not sure what you're asking me to do...
Comment 6 Olivier Crête 2011-11-01 13:12:31 UTC
In the empathy debug window, you can select various debug logs.. on of them is empathy-av
Comment 7 Manuel.Bilderbeek 2011-11-01 15:52:42 UTC
You mean Empathy.AudioVideo?

The only thing that appears there is:

The selected connection manager does not support the remote debugging session.

... that doesn't help, I suppose...
Comment 8 Olivier Crête 2011-11-01 16:01:25 UTC
hmm..
Which version of empathy, farsight2, gst-plugins-good, gst-plugins-bad, gstreamer, telepathy-gabble are you using ?

Does cheese work? (ie, is your webcam working? Where you trying to do an audio only call? or an audio and video call ?
Comment 9 Manuel.Bilderbeek 2011-11-02 10:43:57 UTC
The ones from current Debian testing:

ii  empathy                               3.2.0.1-1                          GNOME multi-protocol chat and call client
ii  libgstfarsight0.10-0                  0.0.31-1                           Audio/Video communications framework: core library
ii  gstreamer0.10-plugins-good            0.10.30-1                          GStreamer plugins from the "good" set
ii  gstreamer0.10-plugins-bad             0.10.22-3                          GStreamer plugins from the "bad" set
ii  gstreamer0.10-x                       0.10.35-1                          GStreamer plugins for X11 and Pango
ii  telepathy-gabble                      0.13.7-1                           Jabber/XMPP connection manager

My webcam works fine with e.g. Google Talk plugin.

I was trying to do an audio and video call.

Anything else I can provide? :)
Comment 10 Will Thompson 2011-11-21 06:01:12 UTC
*** Bug 43092 has been marked as a duplicate of this bug. ***
Comment 11 GitLab Migration User 2019-12-03 19:53:51 UTC
-- GitLab Migration Automatic Message --

This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity.

You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/telepathy/telepathy-gabble/issues/176.

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.