Bug#659189: pulseaudio-module-bluetooth: connection fails for bluetooth (4D)Logitech Adapter

Wed Feb 8 23:36:38 UTC 2012

Package: pulseaudio-module-bluetooth
Version: 1.1-2
Severity: important

I'm currently unable to use my bluetooth audio adapter. The problem is, that
the pulseaudio bluetooth module fails to connect. I have no Idea what is the
problem, since blueman tells me that the audio sink is successfully connected.

My Laptop using Ubuntu can connect to this device, it ceased to work at some
level in the last month. But unfortunately I didn't recognize this fact
immediately, so I can't tell you which update broke the Bluetooth Audio
connection.

For other reasons I switched the hardware from i386 to x86_64, but even a fresh
Debian install didn't help.

I don't know what kind of debugging I can provide. So far I attach an excerpt
from my logs with as much details as I realized to configure:

Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|  15.140)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:349 parse_audio_property()]
dbus: property 'State' changed to value 'connecting'
Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.Audio, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:349 parse_audio_property()]
dbus: property 'State' changed to value 'connecting'
Feb  9 00:09:35 tobias4 pulseaudio[20572]: ( 438.721|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.Audio, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 439.618|   0.896)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.Device, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 439.619|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.Device, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.120|   0.501)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.120|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:349 parse_audio_property()]
dbus: property 'State' changed to value 'connected'
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.AudioSink, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:777 filter_cb()] dbus:
interface=org.bluez.Audio, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:349 parse_audio_property()]
dbus: property 'State' changed to value 'connected'
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/module-bluetooth-discover.c:116
load_module_for_device()] Loading module-bluetooth-device
address="C8:84:47:02:DB:4D" path="/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D"
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/bluetooth/bluetooth-util.c:1597
pa_bluetooth_get_form_factor()] Unknown Bluetooth minor device class 0
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/module-card-restore.c:214 entry_read()] Database contains
invalid data for key: bluez_card.C8_84_47_02_DB_4D (probably pre-v1.0 data)
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/module-card-restore.c:222 entry_read()] Attempting to load
legacy (pre-v1.0) data for key: bluez_card.C8_84_47_02_DB_4D
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/module-card-restore.c:153 legacy_entry_read()] Size does
not match.
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][modules/module-card-restore.c:230 entry_read()] Unable to load
legacy (pre-v1.0) data for key: bluez_card.C8_84_47_02_DB_4D. Ignoring.
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.121|   0.000)
[pulseaudio][pulsecore/card.c:168 pa_card_new()] Created 22
"bluez_card.C8_84_47_02_DB_4D"
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.123|   0.001)
[pulseaudio][modules/bluetooth/module-bluetooth-device.c:2298 init_bt()]
Bluetooth audio service not available
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.123|   0.000)
[pulseaudio][modules/bluetooth/module-bluetooth-device.c:199 service_send()]
Service not connected
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.124|   0.000)
[pulseaudio][pulsecore/card.c:189 pa_card_free()] Freed 22
"bluez_card.C8_84_47_02_DB_4D"
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.124|   0.000)
[pulseaudio][pulsecore/core-subscribe.c:236 pa_subscription_post()] Dropped
redundant event due to remove event.
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.124|   0.000)
[pulseaudio][pulsecore/module.c:110 pa_module_load()] Failed to load module
"module-bluetooth-device" (argument: "address="C8:84:47:02:DB:4D"
path="/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D""): initialization failed.
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.124|   0.000)
[pulseaudio][modules/bluetooth/module-bluetooth-discover.c:127
load_module_for_device()] Failed to load module for device
/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D
Feb  9 00:09:36 tobias4 pulseaudio[20572]: ( 440.124|   0.000)
[pulseaudio][modules/module-console-kit.c:169 filter_cb()] dbus:
interface=org.bluez.Audio, path=/org/bluez/1558/hci0/dev_C8_84_47_02_DB_4D,
member=PropertyChanged

While reading this my Android phone plays audio on the device.

Any further information how to debug this problem?

Tobias

-- System Information:
Debian Release: wheezy/sid
  APT prefers testing-proposed-updates
  APT policy: (500, 'testing-proposed-updates'), (500, 'stable-updates'), (500, 'unstable'), (500, 'testing'), (500, 'stable')
Architecture: amd64 (x86_64)

Kernel: Linux 3.2.0-1-amd64 (SMP w/6 CPU cores)
Locale: LANG=de_DE.UTF-8, LC_CTYPE=de_DE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages pulseaudio-module-bluetooth depends on:
ii  bluez          4.98-2
ii  libbluetooth3  4.98-2
ii  libc6          2.13-26
ii  libcap2        1:2.22-1
ii  libdbus-1-3    1.4.16-1
ii  libpulse0      1.1-2
ii  pulseaudio     1.1-2

pulseaudio-module-bluetooth recommends no packages.

pulseaudio-module-bluetooth suggests no packages.

-- no debconf information