hcid fails to start after upgrade to bluez-libs/bluez-utils 3.1 (Continued)

Bluez-devel Tom K 2 participants 6 messages Jul 3, 2006 – Jul 7, 2006 Bluetooth drivers

lore

Share & tools

lore

Tom K

author

2006-07-03 21:55 UTC #0 lore raw

Apologies for the second thread - I had some system problems which delayed my list subscription request.

Quoted 2 lines · Claudio Takahasi

Hi Tom,

Many thanks for your reply, Claudio.

Quoted 2 lines

Which D-Bus version are you running?

Version 0.62.

Quoted 3 lines

Check if the BlueZ D-Bus configuration file(bluez-hcid.conf) is in the following directory:
/etc/dbus-1/system.d/

Yes, it's there.

Quoted 3 lines

After upgrade bluez-utils you need restart dbus-daemon to load the bluez-hcid.conf file.

I probably didn't do that the first time. I've done it now, though, and things aren't good. I've removed the pin_helper and auth lines from hcid.conf, as per the original error message, and added the passkey line. Starting from a reboot, here are the relevant log entries.

Module loading:
Bluetooth: Core ver 2.8
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: HCI USB driver ver 2.9
usbcore: registered new driver hci_usb

hcid starting:
hcid[2126]: Bluetooth HCI daemon
hcid[2126]: Register path:/org/bluez fallback:1
hcid[2126]: Device hci0 has been added
hcid[2126]: Can't read version info for hci0: Connection timed out (110) hcid[2126]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[2126]: HCI dev 0 up
hcid[2126]: Starting security manager 0

USB disconnect/reconnect:
usb 1-1.1: USB disconnect, address 3
usb 1-1.1: new full speed USB device using uhci_hcd and address 5 usb 1-1.1: configuration #1 chosen from 1 choice

hcid restarting:
hcid[2126]: Can't write inquiry mode for hci0: Connection timed out (110) hcid[2126]: HCI dev 0 down
hcid[2126]: Stoping security manager 0
hcid[2126]: Device hci0 has been disabled
hcid[2126]: HCI dev 0 unregistered
hcid[2126]: Unregister path:/org/bluez/hci0
hcid[2126]: Device hci0 has been removed
hcid[2126]: HCI dev 0 registered
hcid[2126]: Device hci0 has been added
hcid[2126]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[2126]: Sending read scan enable command failed: Connection timed out (110)
hcid[2126]: HCI dev 0 up
hcid[2126]: Starting security manager 0

USB disconnect/reconnect:
usb 1-1.1: USB disconnect, address 5
usb 1-1.1: new full speed USB device using uhci_hcd and address 6 usb 1-1.1: configuration #1 chosen from 1 choice

etc.

The hcid and USB logs above alternated continuously, with the USB address incrementing each time, until I halted hcid.

I then unplugged the dongle, unloaded the module, and started again, with more success this time. Again, here are the logs.

Module:
Bluetooth: Core ver 2.8
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: HCI USB driver ver 2.9
usbcore: registered new driver hci_usb

hcid & USB:
hcid[14066]: Bluetooth HCI daemon
hcid[14066]: Register path:/org/bluez fallback:1
hcid[14066]: Device hci0 has been added
hcid[14066]: Can't write inquiry mode for hci0: Connection timed out (110) hcid[14066]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[14066]: Sending read scan enable command failed: Connection timed out (110)
usb 1-1.1: USB disconnect, address 66
hcid[14066]: Can't read address for hci0: Connection timed out (110) hcid[14066]: HCI dev 0 up
hcid[14066]: Starting security manager 0
hcid[14066]: Can't open device hci0: No such device (19) hcid[14066]: Can't open device hci0
hcid[14066]: HCI dev 0 down
hcid[14066]: Device hci0 has been disabled
hcid[14076]: Can't open device hci0: No such device (19) hcid[14066]: HCI dev 0 unregistered
hcid[14066]: Unregister path:/org/bluez/hci0
usb 1-1.1: new full speed USB device using uhci_hcd and address 67 usb 1-1.1: configuration #1 chosen from 1 choice

There were no further USB disconnects. hciconfig showed the device down, but I was able to bring it up, and then use it as normal.

Regards
Tom K.

Using Tomcat but need to do more? Need to support web services, security? Get stuff done quickly with pre-integrated technology to make your job easier Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Claudio Takahasi

2006-07-04 13:42 UTC #1 lore raw

Hi Tom,

I was not able to reproduce the problem that you described. I found some problems with dongles that doesn't support set the inquiry mode, but I never noticed "Connection timed out".
Could you send the hcidump?

BR,
Claudio.

Signature · 3 lines

---------------------------------------------------------
Claudio Takahasi
Instituto Nokia de Tecnologia - INdT

Quoted 120 lines · Tom K

Apologies for the second thread - I had some system problems which delayed my list subscription request.
Quoted 2 lines · Claudio Takahasi
Hi Tom,
Many thanks for your reply, Claudio.
Quoted 2 lines
Which D-Bus version are you running?
Version 0.62.
Quoted 3 lines
Check if the BlueZ D-Bus configuration file(bluez-hcid.conf) is in the following directory:
/etc/dbus-1/system.d/
Yes, it's there.
Quoted 3 lines
After upgrade bluez-utils you need restart dbus-daemon to load the bluez-hcid.conf file.
I probably didn't do that the first time. I've done it now, though, and things aren't good. I've removed the pin_helper and auth lines from hcid.conf, as per the original error message, and added the passkey line. Starting from a reboot, here are the relevant log entries.
Module loading:
Bluetooth: Core ver 2.8
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: HCI USB driver ver 2.9
usbcore: registered new driver hci_usb
hcid starting:
hcid[2126]: Bluetooth HCI daemon
hcid[2126]: Register path:/org/bluez fallback:1
hcid[2126]: Device hci0 has been added
hcid[2126]: Can't read version info for hci0: Connection timed out (110) hcid[2126]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[2126]: HCI dev 0 up
hcid[2126]: Starting security manager 0
USB disconnect/reconnect:
usb 1-1.1: USB disconnect, address 3
usb 1-1.1: new full speed USB device using uhci_hcd and address 5 usb 1-1.1: configuration #1 chosen from 1 choice
hcid restarting:
hcid[2126]: Can't write inquiry mode for hci0: Connection timed out (110) hcid[2126]: HCI dev 0 down
hcid[2126]: Stoping security manager 0
hcid[2126]: Device hci0 has been disabled
hcid[2126]: HCI dev 0 unregistered
hcid[2126]: Unregister path:/org/bluez/hci0
hcid[2126]: Device hci0 has been removed
hcid[2126]: HCI dev 0 registered
hcid[2126]: Device hci0 has been added
hcid[2126]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[2126]: Sending read scan enable command failed: Connection timed out (110)
hcid[2126]: HCI dev 0 up
hcid[2126]: Starting security manager 0
USB disconnect/reconnect:
usb 1-1.1: USB disconnect, address 5
usb 1-1.1: new full speed USB device using uhci_hcd and address 6 usb 1-1.1: configuration #1 chosen from 1 choice
etc.
The hcid and USB logs above alternated continuously, with the USB address incrementing each time, until I halted hcid.
I then unplugged the dongle, unloaded the module, and started again, with more success this time. Again, here are the logs.
Module:
Bluetooth: Core ver 2.8
NET: Registered protocol family 31
Bluetooth: HCI device and connection manager initialized
Bluetooth: HCI socket layer initialized
Bluetooth: HCI USB driver ver 2.9
usbcore: registered new driver hci_usb
hcid & USB:
hcid[14066]: Bluetooth HCI daemon
hcid[14066]: Register path:/org/bluez fallback:1
hcid[14066]: Device hci0 has been added
hcid[14066]: Can't write inquiry mode for hci0: Connection timed out (110) hcid[14066]: Register path:/org/bluez/hci0 fallback:0
hci_cmd_task: hci0 command tx timeout
hcid[14066]: Sending read scan enable command failed: Connection timed out (110)
usb 1-1.1: USB disconnect, address 66
hcid[14066]: Can't read address for hci0: Connection timed out (110) hcid[14066]: HCI dev 0 up
hcid[14066]: Starting security manager 0
hcid[14066]: Can't open device hci0: No such device (19) hcid[14066]: Can't open device hci0
hcid[14066]: HCI dev 0 down
hcid[14066]: Device hci0 has been disabled
hcid[14076]: Can't open device hci0: No such device (19) hcid[14066]: HCI dev 0 unregistered
hcid[14066]: Unregister path:/org/bluez/hci0
usb 1-1.1: new full speed USB device using uhci_hcd and address 67 usb 1-1.1: configuration #1 chosen from 1 choice
There were no further USB disconnects. hciconfig showed the device down, but I was able to bring it up, and then use it as normal.
Regards
Tom K.

Quoted 9 lines

Using Tomcat but need to do more? Need to support web services, security? Get stuff done quickly with pre-integrated technology to make your job easier Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Tom K

author

2006-07-04 18:11 UTC #2 lore raw

Quoted 10 lines · Claudio Takahasi

Hi Tom,
I was not able to reproduce the problem that you described. I found some problems with dongles that doesn't support set the inquiry mode, but I never noticed "Connection timed out".
Could you send the hcidump?
BR,
Claudio.

Thanks for your continued interest, Claudio.

I'm not able to send a hcidump yet, because I need to rebuild hcidump against bluez-libs 3.1, and I'm getting an error. Not my day, it seems. :(

I'll send the dump file asap.

Tom.

Tom K

author

2006-07-04 21:38 UTC #3 lore raw

In reply to Claudio Takahasi

Quoted 10 lines · Claudio Takahasi

Hi Tom,
I was not able to reproduce the problem that you described. I found some problems with dongles that doesn't support set the inquiry mode, but I never noticed "Connection timed out".
Could you send the hcidump?
BR,
Claudio.

Here's the dump file, Claudio. I started hcidump immediately after plugging in the dongle, and then started the bluetooth daemons. I got the same sequence of USB and hcid log messages as before, and let it cycle through 6 consecutive USB addresses before shutting it down. To my inexpert eye, there doesn't seem to be much in the dump, but hopefully it will mean something to you.

TIA
Tom.

_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Claudio Takahasi

2006-07-07 18:17 UTC #4 lore raw

Hi Tom,

this dump doesn't contain the command status and the command complete events. Could you send it again with "hcidump -X -a -V"?

BR,
Claudio.

Quoted 36 lines · Tom K

Quoted 10 lines · Claudio Takahasi
Hi Tom,
I was not able to reproduce the problem that you described. I found some problems with dongles that doesn't support set the inquiry mode, but I never noticed "Connection timed out".
Could you send the hcidump?
BR,
Claudio.
Here's the dump file, Claudio. I started hcidump immediately after plugging in the dongle, and then started the bluetooth daemons. I got the same sequence of USB and hcid log messages as before, and let it cycle through 6 consecutive USB addresses before shutting it down. To my inexpert eye, there doesn't seem to be much in the dump, but hopefully it will mean something to you.
TIA
Tom.
Using Tomcat but need to do more? Need to support web services, security? Get stuff done quickly with pre-integrated technology to make your job easier Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Signature · 12 lines

---------------------------------------------------------
Claudio Takahasi
Instituto Nokia de Tecnologia - INdT

Using Tomcat but need to do more? Need to support web services, security?
Get stuff done quickly with pre-integrated technology to make your job easier
Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________
Bluez-devel mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/bluez-devel

Tom K

author

2006-07-07 20:48 UTC #5 lore raw

Quoted 8 lines · Claudio Takahasi

Hi Tom,
this dump doesn't contain the command status and the command complete events. Could you send it again with "hcidump -X -a -V"?
BR,
Claudio.

OK - how's this:

$ hcidump -X -a -V -r bt20060704.dump
HCI sniffer - Bluetooth packet analyzer ver 1.29
< HCI Command: Read Local Supported Features (0x04|0x0003) plen 0

Quoted 1 line

HCI Event: Command Status (0x0f) plen 4

    Unknown (0x00|0x0000) status 0x00 ncmd 1

< HCI Command: Read Buffer Size (0x04|0x0005) plen 0

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 12

    Read Local Supported Features (0x04|0x0003) ncmd 1
    status 0x00
    Features: 0xff 0xff 0x05 0x00 0x00 0x00 0x00 0x00

< HCI Command: Read BD ADDR (0x04|0x0009) plen 0

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 11

    Read Buffer Size (0x04|0x0005) ncmd 1
    status 0x00
    ACL MTU 128:8 SCO MTU 64:8

< HCI Command: Read Voice Setting (0x03|0x0025) plen 0

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 10

    Read BD ADDR (0x04|0x0009) ncmd 1
    status 0x00 bdaddr 00:04:76:AD:12:E3

< HCI Command: Set Event Filter (0x03|0x0005) plen 2
type 0 condition 0
Clear all filters

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 6

    Read Voice Setting (0x03|0x0025) ncmd 1
    status 0x00 voice setting 0x0060
< HCI Command: Write Page Timeout (0x03|0x0018) plen 2
    timeout 32768

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 4

    Set Event Filter (0x03|0x0005) ncmd 1
    status 0x00
< HCI Command: Write Connection Accept Timeout (0x03|0x0016) plen 2
    timeout 32000

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 4

    Write Page Timeout (0x03|0x0018) ncmd 1
    status 0x00

< HCI Command: Read Local Version Information (0x04|0x0001) plen 0

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 4

    Write Connection Accept Timeout (0x03|0x0016) ncmd 1
    status 0x00

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 12

    Read Local Version Information (0x04|0x0001) ncmd 1
    status 0x00
    HCI Version: 1.0b (0x0) HCI Revision: 0x61
    LMP Version: 1.1 (0x1) LMP Subversion: 0x61
    Manufacturer: Cambridge Silicon Radio (10)

< HCI Command: Read Local Supported Features (0x04|0x0003) plen 0

Quoted 1 line

HCI Event: Command Complete (0x0e) plen 12

    Read Local Supported Features (0x04|0x0003) ncmd 1
    status 0x00
    Features: 0xff 0xff 0x05 0x00 0x00 0x00 0x00 0x00

< HCI Command: Write Inquiry Mode (0x03|0x0045) plen 1
mode 0
< HCI Command: Reset (0x03|0x0003) plen 0

That's one Status and nine Complete - is that OK, or will I capture a new dump?