[Pkg-utopia-maintainers] Bug#639640: network-manager: Cannot connect to bluez/bluetoothd

Olivier Berger olivier.berger at it-sudparis.eu
Sun Aug 28 22:03:18 UTC 2011 

Package: network-manager
Version: 0.8.4.0-2
Severity: normal

I've setup a DUN connection over bluetooth, with gnome's bluetooth wizard, which appears in NetworkManager.

However, when I try to connect it, it will fail.

Excerpts of syslog (with bluetoothd running with -d option) :
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) starting connection 'Orange No Contract / Mobicarte'
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> (0C:DD:EF:FC:14:07): device state change: 3 -> 4 (reason 0)
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 1 of 5 (Device Prepare) scheduled...
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 1 of 5 (Device Prepare) started...
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 2 of 5 (Device Configure) scheduled...
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 1 of 5 (Device Prepare) complete.
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 2 of 5 (Device Configure) starting...
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> (0C:DD:EF:FC:14:07): device state change: 4 -> 5 (reason 0)
Aug 28 23:39:45 inf-8657 NetworkManager[5441]: <info> Activation (0C:DD:EF:FC:14:07) Stage 2 of 5 (Device Configure) complete.
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:conn_complete() status 0x00
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_features_information() hci0 status 0
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_name_information() hci0 status 0
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:get_auth_info() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x04
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() Matching key found
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() link key type 0x05
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:auth_complete() hci0 status 0
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: plugins/hciops.c:bonding_complete() status 0x00
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: src/event.c:btd_event_bonding_complete() status 0x00
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: src/device.c:device_bonding_complete() bonding (nil) status 0x00
Aug 28 23:39:46 inf-8657 bluetoothd[5730]: Connection refused (111)
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <warn> Error connecting with bluez: Connection refused (111)
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <info> (0C:DD:EF:FC:14:07): device state change: 5 -> 9 (reason 44)
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <info> Marking connection 'Orange No Contract / Mobicarte' invalid.
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <warn> Activation (0C:DD:EF:FC:14:07) failed.
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <info> (0C:DD:EF:FC:14:07): device state change: 9 -> 3 (reason 0)
Aug 28 23:39:46 inf-8657 NetworkManager[5441]: <info> (0C:DD:EF:FC:14:07): deactivating device (reason: 0).
Aug 28 23:39:50 inf-8657 bluetoothd[5730]: plugins/hciops.c:disconn_complete() handle 43 status 0x00
Aug 28 23:39:50 inf-8657 bluetoothd[5730]: src/event.c:btd_event_disconn_complete() 
Aug 28 23:39:50 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_remove_connection() 


Whereas, when using rfcomm to initialize rfcomm0, and wvdial to connect, it succeeds. FYI, in such case, bluetoothd reports :

Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:conn_complete() status 0x00
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_features_information() hci0 status 0
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_name_information() hci0 status 0
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:get_auth_info() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x00
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() Matching key found
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() link key type 0x05
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:auth_complete() hci0 status 0
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: plugins/hciops.c:bonding_complete() status 0x00
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: src/event.c:btd_event_bonding_complete() status 0x00
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:51:57 inf-8657 bluetoothd[5730]: src/device.c:device_bonding_complete() bonding (nil) status 0x00
Aug 28 23:52:13 inf-8657 bluetoothd[5730]: plugins/hciops.c:disconn_complete() handle 43 status 0x00
Aug 28 23:52:13 inf-8657 bluetoothd[5730]: src/event.c:btd_event_disconn_complete() 
Aug 28 23:52:13 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_remove_connection() 
Aug 28 23:52:18 inf-8657 bluetoothd[5730]: plugins/hciops.c:conn_complete() status 0x00
Aug 28 23:52:18 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:52:18 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_features_information() hci0 status 0
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:remote_name_information() hci0 status 0
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:get_auth_info() hci0 dba 0C:DD:EF:FC:14:07
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x00
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() Matching key found
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:link_key_request() link key type 0x05
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:auth_complete() hci0 status 0
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: plugins/hciops.c:bonding_complete() status 0x00
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: src/event.c:btd_event_bonding_complete() status 0x00
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: src/adapter.c:adapter_get_device() 0C:DD:EF:FC:14:07
Aug 28 23:52:19 inf-8657 bluetoothd[5730]: src/device.c:device_bonding_complete() bonding (nil) status 0x00
Aug 28 23:52:54 inf-8657 kernel: [ 6647.509488] rfcomm_tty_ioctl: TIOCGSERIAL is not supported
Aug 28 23:52:57 inf-8657 pppd[6040]: pppd 2.4.5 started by root, uid 0
Aug 28 23:52:57 inf-8657 kernel: [ 6651.236066] PPP generic driver version 2.4.2
Aug 28 23:52:57 inf-8657 pppd[6040]: Using interface ppp0
Aug 28 23:52:57 inf-8657 pppd[6040]: Connect: ppp0 <--> /dev/rfcomm0
Aug 28 23:52:58 inf-8657 pppd[6040]: PAP authentication succeeded
Aug 28 23:52:58 inf-8657 kernel: [ 6651.484322] PPP BSD Compression module registered
Aug 28 23:52:58 inf-8657 kernel: [ 6651.492200] PPP Deflate Compression module registered
Aug 28 23:53:00 inf-8657 dnsmasq[2297]: no servers found in /etc/resolv.conf, will retry
Aug 28 23:53:00 inf-8657 pppd[6040]: local  IP address 10.97.160.236
Aug 28 23:53:00 inf-8657 pppd[6040]: remote IP address 10.6.6.6
etc.


I hence think there's something wrong in the way NM tries to connect to bluetoothd (if it's what's identified by "bluez").

Thanks in advance.

Hope this helps.

Best regards,

-- System Information:
Debian Release: wheezy/sid
  APT prefers testing
  APT policy: (900, 'testing'), (300, 'stable')
Architecture: i386 (i686)

Kernel: Linux 3.0.0-1-686-pae (SMP w/2 CPU cores)
Locale: LANG=fr_FR.utf8, LC_CTYPE=fr_FR.utf8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages network-manager depends on:
ii  adduser                      3.113       add and remove users and groups
ii  dbus                         1.4.14-1    simple interprocess messaging syst
ii  isc-dhcp-client              4.1.1-P1-17 ISC DHCP client
ii  libc6                        2.13-16     Embedded GNU C Library: Shared lib
ii  libdbus-1-3                  1.4.14-1    simple interprocess messaging syst
ii  libdbus-glib-1-2             0.94-4      simple interprocess messaging syst
ii  libgcrypt11                  1.4.6-9     LGPL Crypto library - runtime libr
ii  libglib2.0-0                 2.28.6-1    The GLib library of C routines
ii  libgnutls26                  2.12.7-7    GNU TLS library - runtime library
ii  libgudev-1.0-0               172-1       GObject-based wrapper library for 
ii  libnl1                       1.1-7       library for dealing with netlink s
ii  libnm-glib2                  0.8.4.0-2   network management framework (GLib
ii  libnm-util1                  0.8.4.0-2   network management framework (shar
ii  libpolkit-gobject-1-0        0.102-1     PolicyKit Authorization API
ii  libuuid1                     2.19.1-5    Universally Unique ID library
ii  lsb-base                     3.2-27      Linux Standard Base 3.2 init scrip
ii  udev                         172-1       /dev/ and hotplug management daemo
ii  wpasupplicant                0.7.3-3     client support for WPA and WPA2 (I

Versions of packages network-manager recommends:
ii  dnsmasq-base                  2.57-1     A small caching DNS proxy and DHCP
ii  iptables                      1.4.12-1   administration tools for packet fi
ii  modemmanager                  0.5-1      D-Bus service for managing modems
ii  policykit-1                   0.102-1    framework for managing administrat
ii  ppp                           2.4.5-5    Point-to-Point Protocol (PPP) - da

Versions of packages network-manager suggests:
ii  avahi-autoipd                 0.6.30-5   Avahi IPv4LL network address confi

-- Configuration Files:
/etc/NetworkManager/NetworkManager.conf changed:
[main]
plugins=ifupdown,keyfile
[ifupdown]
managed=true


-- debconf-show failed

More information about the Pkg-utopia-maintainers mailing list