Ubuntu
linux package

Bug #570692
Comment #19

Comment 19 for bug 570692

Revision history for this message

Jan Bakuwel (jan-bakuwel-gmail) wrote on 2010-06-21:

#19

I can also confirm this problem (Ubuntu 10.04 32bit) using bluetooth to connect to Nokia E61i). It makes no difference if I do this as root (sudo) or not. The output from UDEV_LOG=debug udevadm test $(udevadm info -q path -n rfcomm0) below. Could:

udev_device_update_db: unable to create temporary db file '/dev/.udev/db/tty:rfcomm0.tmp': Permission denied

be a problem? The file exists though:

$ ls -al /dev/.udev/db/tty\:rfcomm0
lrwxrwxrwx 1 root root 18 2010-06-21 17:16 /dev/.udev/db/tty:rfcomm0 -> rfcomm0 char/216:0

I tried removing the link - makes no difference.

$ ls -al /dev/rfcomm0
crw-rw---- 1 root dialout 216, 0 2010-06-21 17:46 /dev/rfcomm0
$ id
uid=2000(UN) gid=2000(UN) groups=4(adm),20(dialout),21(fax),24(cdrom),25(floppy),26(tape),29(audio),30(dip),44(video),46(plugdev),104(fuse),105(lpadmin),112(netdev),119(admin),122(sambashare),2000(UN)

Username substituted for UN.

udev_rules_new: rules use 209676 bytes tokens (17473 * 12 bytes), 33446 bytes buffer
udev_rules_new: temporary index used 55540 bytes (2777 * 20 bytes)
udev_device_new_from_syspath: device 0x21bba248 has devpath '/devices/virtual/tty/rfcomm0'
udev_device_new_from_syspath: device 0x21bba3f0 has devpath '/devices/virtual/tty/rfcomm0'
udev_device_read_db: device 0x21bba3f0 filled with db symlink data '/dev/rfcomm0'
udev_rules_apply_to_event: LINK 'char/216:0' /lib/udev/rules.d/50-udev-default.rules:4
udev_rules_apply_to_event: GROUP 20 /lib/udev/rules.d/50-udev-default.rules:15
udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
udev_event_execute_rules: no node name set, will use kernel supplied name 'rfcomm0'
udev_device_update_db: unable to create temporary db file '/dev/.udev/db/tty:rfcomm0.tmp': Permission denied
udev_node_add: creating device node '/dev/rfcomm0', devnum=216:0, mode=0660, uid=0, gid=20
udev_node_mknod: preserve file '/dev/rfcomm0', because it has correct dev_t
node_symlink: preserve already existing symlink '/dev/char/216:0' to '../rfcomm0'
udevadm_test: UDEV_LOG=7
udevadm_test: DEVPATH=/devices/virtual/tty/rfcomm0
udevadm_test: MAJOR=216
udevadm_test: MINOR=0
udevadm_test: DEVNAME=/dev/rfcomm0
udevadm_test: ACTION=add
udevadm_test: SUBSYSTEM=tty
udevadm_test: DEVLINKS=/dev/char/216:0
udevadm_test: run: 'socket:@/org/freedesktop/hal/udev_event'

$ pppd nomagic call bluetooth
Failed to open /dev/rfcomm0: Connection refused
$ sudo pppd nomagic call bluetooth
[sudo] password for UN:
Failed to open /dev/rfcomm0: Connection refused
$

Please note that I can't open the device (/dev/rfcomm0) with minicom or the Serial Port Terminal (same error).
It's not a device permissions problem... there is some communication with the Nokia E61i (as shown by hcidump):

$ sudo hcidump -t
HCI sniffer - Bluetooth packet analyzer ver 1.42
device: hci0 snap_len: 1028 filter: 0xffffffff
1277101579.840190 < HCI Command: Create Connection (0x01|0x0005) plen 13
1277101579.847549 > HCI Event: Command Status (0x0f) plen 4
1277101580.315554 > HCI Event: Connect Complete (0x03) plen 11
1277101580.315587 < HCI Command: Read Remote Supported Features (0x01|0x001b) plen 2
1277101580.333546 > HCI Event: Command Status (0x0f) plen 4
1277101580.336539 > HCI Event: Max Slots Change (0x1b) plen 3
1277101580.340539 > HCI Event: Command Status (0x0f) plen 4
1277101580.340563 < HCI Command: Remote Name Request (0x01|0x0019) plen 10
1277101580.346547 > HCI Event: Command Status (0x0f) plen 4
1277101580.349545 > HCI Event: Read Remote Supported Features (0x0b) plen 11
1277101580.349568 < ACL data: handle 46 flags 0x02 dlen 10
    L2CAP(s): Info req: type 2
1277101580.356543 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.394549 > HCI Event: Remote Name Req Complete (0x07) plen 255
1277101580.437564 > ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Info rsp: type 2 result 0
      Extended feature mask 0x0003
1277101580.437597 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Connect req: psm 3 scid 0x0040
1277101580.444543 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.516566 > ACL data: handle 46 flags 0x02 dlen 27
1277101580.518553 > ACL data: handle 46 flags 0x01 dlen 1
    L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 1 status 2
      Connection pending - Authorization pending
    L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 0 status 0
      Connection successful
1277101580.518575 < ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4
      MTU 1013
1277101580.521554 > ACL data: handle 46 flags 0x02 dlen 10
    L2CAP(s): Info req: type 2
1277101580.521571 < ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Info rsp: type 2 result 0
      Extended feature mask 0x0080
1277101580.526548 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.528547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.589562 > ACL data: handle 46 flags 0x02 dlen 26
    L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
      MTU 672
    L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 0
1277101580.589598 < ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
      MTU 672
1277101580.589621 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): SABM: cr 1 dlci 0 pf 1 ilen 0 fcs 0x1c
1277101580.595546 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.597546 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.606546 > ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): UA: cr 1 dlci 0 pf 1 ilen 0 fcs 0xd7
1277101580.606588 < ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(d): cid 0x0040 len 14 [psm 3]
      RFCOMM(s): PN CMD: cr 1 dlci 0 pf 0 ilen 10 fcs 0x70 mcc_len 8
      dlci 4 frame_type 0 credit_flow 15 pri 7 ack_timer 0
      frame_size 667 max_retrans 0 credits 7
1277101580.613553 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.624558 > ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(d): cid 0x0040 len 14 [psm 3]
      RFCOMM(s): PN RSP: cr 0 dlci 0 pf 0 ilen 10 fcs 0xaa mcc_len 8
      dlci 4 frame_type 0 credit_flow 14 pri 7 ack_timer 0
      frame_size 667 max_retrans 0 credits 3
1277101580.624619 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): SABM: cr 1 dlci 4 pf 1 ilen 0 fcs 0x96
1277101580.630552 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.647548 > HCI Event: Link Key Request (0x17) plen 6
1277101580.647794 < HCI Command: Link Key Request Reply (0x01|0x000b) plen 22
1277101580.664538 > HCI Event: Command Complete (0x0e) plen 10
1277101580.670545 > ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): DM: cr 1 dlci 4 pf 1 ilen 0 fcs 0xbc
1277101582.668256 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): DISC: cr 1 dlci 0 pf 1 ilen 0 fcs 0xfd
1277101582.668285 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
1277101582.673558 > ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
1277101582.673588 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
1277101582.675550 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.677547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.679547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.681548 > ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
1277101584.671864 < HCI Command: Disconnect (0x01|0x0006) plen 3
1277101584.678539 > HCI Event: Command Status (0x0f) plen 4
1277101584.684550 > HCI Event: Disconn Complete (0x05) plen 4

The phone has been paired successfully with the computer. I've removed and re-created the pairing, didn't make a difference.

I noticed that even though it is possible to use the BlueTooth menu (applet) to initiate file browsing on the phone, it doesn't work, possibly because of the same issue?

I wouldn't know what else to test/verify ... hopefully someone here can provide some pointers.

udev_device_update_db: unable to create temporary db file '/dev/.udev/db/tty:rfcomm0.tmp': Permission denied

be a problem? The file exists though:

$ ls -al /dev/.udev/db/tty\:rfcomm0 
lrwxrwxrwx 1 root root 18 2010-06-21 17:16 /dev/.udev/db/tty:rfcomm0 -> rfcomm0 char/216:0

I tried removing the link - makes no difference.

$ ls -al /dev/rfcomm0 
crw-rw---- 1 root dialout 216, 0 2010-06-21 17:46 /dev/rfcomm0
$ id
uid=2000(UN) gid=2000(UN) groups=4(adm),20(dialout),21(fax),24(cdrom),25(floppy),26(tape),29(audio),30(dip),44(video),46(plugdev),104(fuse),105(lpadmin),112(netdev),119(admin),122(sambashare),2000(UN)

Username substituted for UN.

$ pppd nomagic call bluetooth
Failed to open /dev/rfcomm0: Connection refused
$ sudo pppd nomagic call bluetooth
[sudo] password for UN: 
Failed to open /dev/rfcomm0: Connection refused
$

$ sudo hcidump -t
HCI sniffer - Bluetooth packet analyzer ver 1.42
device: hci0 snap_len: 1028 filter: 0xffffffff
1277101579.840190 < HCI Command: Create Connection (0x01|0x0005) plen 13
1277101579.847549 > HCI Event: Command Status (0x0f) plen 4
1277101580.315554 > HCI Event: Connect Complete (0x03) plen 11
1277101580.315587 < HCI Command: Read Remote Supported Features (0x01|0x001b) plen 2
1277101580.333546 > HCI Event: Command Status (0x0f) plen 4
1277101580.336539 > HCI Event: Max Slots Change (0x1b) plen 3
1277101580.340539 > HCI Event: Command Status (0x0f) plen 4
1277101580.340563 < HCI Command: Remote Name Request (0x01|0x0019) plen 10
1277101580.346547 > HCI Event: Command Status (0x0f) plen 4
1277101580.349545 > HCI Event: Read Remote Supported Features (0x0b) plen 11
1277101580.349568 < ACL data: handle 46 flags 0x02 dlen 10
    L2CAP(s): Info req: type 2
1277101580.356543 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.394549 > HCI Event: Remote Name Req Complete (0x07) plen 255
1277101580.437564 > ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Info rsp: type 2 result 0
      Extended feature mask 0x0003
1277101580.437597 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Connect req: psm 3 scid 0x0040
1277101580.444543 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.516566 > ACL data: handle 46 flags 0x02 dlen 27
1277101580.518553 > ACL data: handle 46 flags 0x01 dlen 1
    L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 1 status 2
      Connection pending - Authorization pending
    L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 0 status 0
      Connection successful
1277101580.518575 < ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4
      MTU 1013 
1277101580.521554 > ACL data: handle 46 flags 0x02 dlen 10
    L2CAP(s): Info req: type 2
1277101580.521571 < ACL data: handle 46 flags 0x02 dlen 16
    L2CAP(s): Info rsp: type 2 result 0
      Extended feature mask 0x0080
1277101580.526548 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.528547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.589562 > ACL data: handle 46 flags 0x02 dlen 26
    L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
      MTU 672 
    L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 0
1277101580.589598 < ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
      MTU 672 
1277101580.589621 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): SABM: cr 1 dlci 0 pf 1 ilen 0 fcs 0x1c 
1277101580.595546 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.597546 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.606546 > ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): UA: cr 1 dlci 0 pf 1 ilen 0 fcs 0xd7 
1277101580.606588 < ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(d): cid 0x0040 len 14 [psm 3]
      RFCOMM(s): PN CMD: cr 1 dlci 0 pf 0 ilen 10 fcs 0x70 mcc_len 8
      dlci 4 frame_type 0 credit_flow 15 pri 7 ack_timer 0
      frame_size 667 max_retrans 0 credits 7
1277101580.613553 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.624558 > ACL data: handle 46 flags 0x02 dlen 18
    L2CAP(d): cid 0x0040 len 14 [psm 3]
      RFCOMM(s): PN RSP: cr 0 dlci 0 pf 0 ilen 10 fcs 0xaa mcc_len 8
      dlci 4 frame_type 0 credit_flow 14 pri 7 ack_timer 0
      frame_size 667 max_retrans 0 credits 3
1277101580.624619 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): SABM: cr 1 dlci 4 pf 1 ilen 0 fcs 0x96 
1277101580.630552 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101580.647548 > HCI Event: Link Key Request (0x17) plen 6
1277101580.647794 < HCI Command: Link Key Request Reply (0x01|0x000b) plen 22
1277101580.664538 > HCI Event: Command Complete (0x0e) plen 10
1277101580.670545 > ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): DM: cr 1 dlci 4 pf 1 ilen 0 fcs 0xbc 
1277101582.668256 < ACL data: handle 46 flags 0x02 dlen 8
    L2CAP(d): cid 0x0040 len 4 [psm 3]
      RFCOMM(s): DISC: cr 1 dlci 0 pf 1 ilen 0 fcs 0xfd 
1277101582.668285 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
1277101582.673558 > ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
1277101582.673588 < ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
1277101582.675550 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.677547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.679547 > HCI Event: Number of Completed Packets (0x13) plen 5
1277101582.681548 > ACL data: handle 46 flags 0x02 dlen 12
    L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
1277101584.671864 < HCI Command: Disconnect (0x01|0x0006) plen 3
1277101584.678539 > HCI Event: Command Status (0x0f) plen 4
1277101584.684550 > HCI Event: Disconn Complete (0x05) plen 4

The phone has been paired successfully with the computer. I've removed and re-created the pairing, didn't make a difference.

I noticed that even though it is possible to use the BlueTooth menu (applet) to initiate file browsing on the phone, it doesn't work, possibly because of the same issue?

I wouldn't know what else to test/verify ... hopefully someone here can provide some pointers.

Ubuntulinux package

Comment 19 for bug 570692

Ubuntu
linux package