[Telepathy] Cannot hear sip audio

Fri Dec 19 05:14:09 PST 2014

Hello,

I've got a sip account.  When placing a sip call (audio only), I cannot 
hear the person on the other side, but they can hear me.

I'm trying to fix it, but I don't know how to get relevant debug 
information.  I'm hoping someone can help me.

fedora/gnome/empathy/telepathy

I've checked mute buttons.  I've also checked that empathy makes a 
pulseaudio application connection.  It does, the application connection 
is on, and has volume.

As soon as I place a call, the audio out is automatically muted.  It's 
muted every time.  I unmute this when the call starts.  Microphone is 
not muted.

Debug empathy of a call attached.  possible problem: "local codecs:" 
lists nothing.

user/pass: withheld for now
nat: (since they can hear me, network up???)
  no stun
  "tick" discover binding
proxy:
  same IP as account, port 5060
keep-alive: (call only disconnects when either side hangs up)
  auto
  interval 0 seconds
misc:
  blank

The only errors on stdout/stderr are:

OBEX errors:

(empathy:31875): folks-WARNING **: Error preparing persona store 
'bluez:F0:E7:7E:9E:C0:01': An OBEX address book transfer from device 
‘ephebe’ could not be started: 
GDBus.Error:org.bluez.obex.Error.Failed: Timed out waiting for response

addressbook errors:

(empathy:31875): tp-glib-CRITICAL **: tp_connection_get_contacts_by_id: 
assertion 'tp_proxy_is_prepared (self, 
TP_CONNECTION_FEATURE_CONNECTED)' failed

empathyOther-DEBUG: 19/12/2014 13:00:01.500564: 
empathy_sound_play_internal: Play sound "phone-outgoing-calling" 
(Outgoing voice call)
empathyVoip-DEBUG: 19/12/2014 13:00:01.556097: 
empathy_call_window_conference_added_cb: Conference added
empathyVoip-DEBUG: 19/12/2014 13:00:01.579895: 
empathy_audio_src_set_echo_cancel: Src echo cancellation setting: on
empathyOther-DEBUG: 19/12/2014 13:00:01.580074: 
empathy_audio_set_stream_properties: Echo cancellation: element 
allowed: yes, user enabled: yes
empathyVoip-DEBUG: 19/12/2014 13:00:01.588546: 
empathy_audio_sink_set_echo_cancel: Sink echo cancellation setting: on
tp-fs/-DEBUG: 19/12/2014 13:00:01.588781: Got MediaDescription 
/org/freedesktop/Telepathy/Connection/sofiasip/sip/_30117121435_40196_2e31_2e29_2e60/CallChannel4/Content1/InitialOffer
tp-fs/-DEBUG: 19/12/2014 13:00:01.588788: Delaying codec 
media_description processing
tp-fs/-DEBUG: 19/12/2014 13:00:01.595098: Transmitter: rawudp
tp-fs/-DEBUG: 19/12/2014 13:00:01.595112: Requesting that the 
application start receiving
tp-fs/-DEBUG: 19/12/2014 13:00:01.595118: Request to start receiving 
succeeded
tp-fs/-DEBUG: 19/12/2014 13:00:01.603800: updating local codecs: 0
tp-fs/-DEBUG: 19/12/2014 13:00:01.603837: Local codecs:
tp-fs/-DEBUG: 19/12/2014 13:00:01.603843: 96: audio 
X-GST-OPUS-DRAFT-SPITTKA-00 clock:48000 channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.603880: 102: audio SPEEX clock:8000 
channels:1
tp-fs/-DEBUG: 19/12/2014 13:00:01.603899: 103: audio SPEEX clock:16000 
channels:1
tp-fs/-DEBUG: 19/12/2014 13:00:01.603921: 98: audio AMR clock:8000 
channels:1 octet-align=1 crc=0 robust-sorting=0 interleaving=0
tp-fs/-DEBUG: 19/12/2014 13:00:01.603952: 0: audio PCMU clock:8000 
channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.603971: 8: audio PCMA clock:8000 
channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.603990: 14: audio MPA-ROBUST 
clock:90000 channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.604007: 3: audio GSM clock:8000 
channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.604026: 9: audio G722 clock:8000 
channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:01.604046: 101: audio telephone-event 
clock:8000 channels:0 events=0-15
tp-fs/-DEBUG: 19/12/2014 13:00:01.604068: 100: audio telephone-event 
clock:48000 channels:0 events=0-15
tp-fs/-DEBUG: 19/12/2014 13:00:01.604091: 104: audio telephone-event 
clock:90000 channels:0 events=0-15
tp-fs/-DEBUG: 19/12/2014 13:00:01.604165: Accepting Media Description 
for contact: 11
tp-fs/-DEBUG: 19/12/2014 13:00:01.610430: Codecs changed
tp-fs/-DEBUG: 19/12/2014 13:00:01.610440: updating local codecs: 0
tp-fs/-DEBUG: 19/12/2014 13:00:01.612032: Codecs changed
tp-fs/-DEBUG: 19/12/2014 13:00:01.612041: updating local codecs: 0
/-WARNING: 19/12/2014 13:00:01.634922: Error creating GUPnP context: 
Failed to find IP of interface virbr0-nic
empathyOther-DEBUG: 19/12/2014 13:00:03.196839: 
empathy_sound_play_internal: Play sound "phone-outgoing-calling" 
(Outgoing voice call)
tp-fs/-DEBUG: 19/12/2014 13:00:04.20174: Local Candidate: L1 c:1 
fstype:0 fsproto: 0 ip:192.168.122.1 port:7078 prio:0 u/p:(null)/(null) 
ttl:0 base_ip:(null) base_port:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.20400: Local Candidate: L1 c:2 
fstype:0 fsproto: 0 ip:192.168.122.1 port:7079 prio:0 u/p:(null)/(null) 
ttl:0 base_ip:(null) base_port:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.20523: Local candidates prepared
tp-fs/-DEBUG: 19/12/2014 13:00:04.137422: Got MediaDescription 
/org/freedesktop/Telepathy/Connection/sofiasip/sip/_30117121435_40196_2e31_2e29_2e60/CallChannel4/Content1/Offer1
tp-fs/-DEBUG: 19/12/2014 13:00:04.137437: 0: audio PCMU clock:8000 
channels:0 maxptime=40
tp-fs/-DEBUG: 19/12/2014 13:00:04.137447: 101: audio telephone-event 
clock:8000 channels:0 maxptime=40 events=0-15
tp-fs/-DEBUG: 19/12/2014 13:00:04.138294: updating local codecs: 0
tp-fs/-DEBUG: 19/12/2014 13:00:04.138339: Local codecs:
tp-fs/-DEBUG: 19/12/2014 13:00:04.138345: 0: audio PCMU clock:8000 
channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.138374: 101: audio telephone-event 
clock:8000 channels:0 events=0-15
tp-fs/-DEBUG: 19/12/2014 13:00:04.138416: Accepting Media Description 
for contact: 11
empathyOther-DEBUG: 19/12/2014 13:00:04.139731: playing_finished_cb: 
Error: Canceled
tp-fs/-DEBUG: 19/12/2014 13:00:04.139561: Codecs changed
tp-fs/-DEBUG: 19/12/2014 13:00:04.139566: updating local codecs: 0
tp-fs/-DEBUG: 19/12/2014 13:00:04.143532: Requesting that the 
application start sending
tp-fs/-DEBUG: 19/12/2014 13:00:04.143537: Request to start sending 
succeeded
tp-fs/-DEBUG: 19/12/2014 13:00:04.144210: Got Endpoint Properties
tp-fs/-DEBUG: 19/12/2014 13:00:04.144220: Remote Candidate: c:1 
tptype:1 tpproto: 0 ip:196.31.29.60 port:16822 prio:0 u/p:(null)/(null) 
ttl:0 base_ip:(null) base_port:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.144224: Remote Candidate: c:2 
tptype:1 tpproto: 0 ip:196.31.29.60 port:16823 prio:0 u/p:(null)/(null) 
ttl:0 base_ip:(null) base_port:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.144892: new active candidate pair 
local: 192.168.122.1 (7078) remote: 196.31.29.60 (16822)
tp-fs/-DEBUG: 19/12/2014 13:00:04.145669: new active candidate pair 
local: 192.168.122.1 (7079) remote: 196.31.29.60 (16823)
tp-fs/-DEBUG: 19/12/2014 13:00:04.160162: Send codec changed: 0: audio 
PCMU clock:8000 channels:0
tp-fs/-DEBUG: 19/12/2014 13:00:04.160171: Secondary send codec 1 
changed: 101: audio telephone-event clock:8000 channels:0 events=0-15
empathyVoip-DEBUG: 19/12/2014 13:00:25.87414: 
empathy_call_window_state_changed_cb: Call ended: unspecified reason
tp-fs/-DEBUG: 19/12/2014 13:00:25.83225: tf_channel_dispose
tp-fs/-DEBUG: 19/12/2014 13:00:25.83231: tf_call_channel_dispose
tp-fs/-DEBUG: 19/12/2014 13:00:25.83350: Requesting that the 
application stop receiving