Armadilloフォーラム

Bluetooth通信について

hanako

2018年11月29日 12時06分

お世話になっております。
Bluetooth通信に関して、お伺いしたいことがございます。

BT機器からBT-SPPにて、センサデータを連続受信中、突然以下メッセージが
コンソールに出力されて、通信が遮断されてしまうという現象が発生しております。

usb 2-1.3: USB disconnect, device number 9
usb 2-1.3: new full-speed USB device number 10 using ci_hdrc
usb 2-1.3: USB disconnect, device number 10
usb 2-1.3: new full-speed USB device number 11 using ci_hdrc

/var/log/syslogにも同事象が記録されています。

Nov 29 01:37:46 armadillo kernel: usb 2-1.3: USB disconnect, device number 9
Nov 29 01:37:46 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status..
.
Nov 29 01:37:46 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Nov 29 01:37:46 armadillo systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Nov 29 01:37:46 armadillo systemd[1]: Stopped target Bluetooth.
Nov 29 01:37:47 armadillo kernel: usb 2-1.3: new full-speed USB device number 10 using ci_hdrc
Nov 29 01:37:47 armadillo kernel: usb 2-1.3: USB disconnect, device number 10
Nov 29 01:37:47 armadillo kernel: usb 2-1.3: new full-speed USB device number 11 using ci_hdrc
Nov 29 01:37:52 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
Nov 29 01:37:53 armadillo systemd[1]: Reached target Bluetooth.
Nov 29 01:37:53 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Nov 29 01:37:53 armadillo bluetoothd[2164]: Failed to obtain handles for "Service Changed" characteristic
Nov 29 01:37:53 armadillo bluetoothd[2164]: Sap driver initialization failed.
Nov 29 01:37:53 armadillo bluetoothd[2164]: sap-server: Operation not permitted (1)

センサデータ受信プログラムでは、recv()関数実行中に、exceptionをキャッチし、
以下の例外を受信しています。
(103, 'Software caused connection abort')

恐らく、内部構造上Armadillo G3内部では、BluetoothがUSBデバイス上に
付いていて、それが外された様に見えるために、この様な現象になっているのだと推測しています。

本現象の対策方法をご教授ください。

コメント

hanako

2018年11月29日 16時21分

お世話になっております。

補足ですが、
早ければ、4時間くらいで減少発生。
もっとも長く運転していても、20時間くらいで発生。
と「発生頻度も少ない」状況です。

> お世話になっております。
> Bluetooth通信に関して、お伺いしたいことがございます。
>
> BT機器からBT-SPPにて、センサデータを連続受信中、突然以下メッセージが
> コンソールに出力されて、通信が遮断されてしまうという現象が発生しております。
>
> usb 2-1.3: USB disconnect, device number 9
> usb 2-1.3: new full-speed USB device number 10 using ci_hdrc
> usb 2-1.3: USB disconnect, device number 10
> usb 2-1.3: new full-speed USB device number 11 using ci_hdrc
>
> /var/log/syslogにも同事象が記録されています。
>
> Nov 29 01:37:46 armadillo kernel: usb 2-1.3: USB disconnect, device number 9
> Nov 29 01:37:46 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status..
> .
> Nov 29 01:37:46 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
> Nov 29 01:37:46 armadillo systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
> Nov 29 01:37:46 armadillo systemd[1]: Stopped target Bluetooth.
> Nov 29 01:37:47 armadillo kernel: usb 2-1.3: new full-speed USB device number 10 using ci_hdrc
> Nov 29 01:37:47 armadillo kernel: usb 2-1.3: USB disconnect, device number 10
> Nov 29 01:37:47 armadillo kernel: usb 2-1.3: new full-speed USB device number 11 using ci_hdrc
> Nov 29 01:37:52 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
> Nov 29 01:37:53 armadillo systemd[1]: Reached target Bluetooth.
> Nov 29 01:37:53 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
> Nov 29 01:37:53 armadillo bluetoothd[2164]: Failed to obtain handles for "Service Changed" characteristic
> Nov 29 01:37:53 armadillo bluetoothd[2164]: Sap driver initialization failed.
> Nov 29 01:37:53 armadillo bluetoothd[2164]: sap-server: Operation not permitted (1)
>
> センサデータ受信プログラムでは、recv()関数実行中に、exceptionをキャッチし、
> 以下の例外を受信しています。
> (103, 'Software caused connection abort')
>
> 恐らく、内部構造上Armadillo G3内部では、BluetoothがUSBデバイス上に
> 付いていて、それが外された様に見えるために、この様な現象になっているのだと推測しています。
>
> 本現象の対策方法をご教授ください。

at_keitaro.takahashi

2018年11月29日 17時26分

高橋です。

こちらでもSPP対応の製品を用いて確認します。
以下のコマンドの実行結果を教えていただけますか?

uname -a
dpkg -l | grep bluez

hanako

2018年11月30日 9時47分

お世話になっております。

> こちらでもSPP対応の製品を用いて確認します。

よろしくお願いいたします。

> 以下のコマンドの実行結果を教えていただけますか?

以下の通りです。
$ uname -a
Linux armadillo 4.9.112-at2 #2 SMP PREEMPT Thu Sep 27 10:54:16 JST 2018 armv7l GNU/Linux

$ dpkg -l | grep bluez
ii bluez 5.43-2+deb9u1 armhf Bluetooth tools and daemons

at_keitaro.takahashi

2018年11月30日 11時42分

高橋です。

ご確認ありがとうございます。
頂いた情報を元に、こちらでも確認いたします。

> Linux armadillo 4.9.112-at2 #2 SMP PREEMPT Thu Sep 27 10:54:16 JST 2018 armv7l GNU/Linux

現在の、弊社Linuxカーネルの最新版は4.9.133-at3となります。
Linuxカーネルを最新版に更新した上で、今一度動作の確認をお願い致します。

hanako

2018年12月4日 9時49分

お世話になっております。

最新版で確認致しましたが、現象は変わりませんでした。
ログとコマンドの実行結果は、下記の通りです。

Dec 3 19:57:44 armadillo kernel: usb 2-1.3: USB disconnect, device number 4
Dec 3 19:57:45 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 3 19:57:45 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 3 19:57:45 armadillo systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Dec 3 19:57:45 armadillo systemd[1]: Stopped target Bluetooth.
Dec 3 19:57:45 armadillo kernel: usb 2-1.3: new full-speed USB device number 6 using ci_hdrc
Dec 3 19:57:45 armadillo kernel: usb 2-1.3: USB disconnect, device number 6
Dec 3 19:57:45 armadillo kernel: usb 2-1.3: new full-speed USB device number 7 using ci_hdrc
Dec 3 19:57:51 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 3 19:57:51 armadillo systemd[1]: Reached target Bluetooth.
Dec 3 19:57:51 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 3 19:57:51 armadillo bluetoothd[31628]: Failed to obtain handles for "Service Changed" characteristic
Dec 3 19:57:51 armadillo bluetoothd[31628]: Sap driver initialization failed.
Dec 3 19:57:51 armadillo bluetoothd[31628]: sap-server: Operation not permitted (1)

$ uname -a
Linux armadillo 4.9.133-at3 #2 SMP PREEMPT Thu Oct 25 15:23:15 JST 2018 armv7l GNU/Linux

$ dpkg -l | grep bluez
ii bluez 5.43-2+deb9u1 armhf Bluetooth tools and daemons

at_keitaro.takahashi

2018年12月4日 11時15分

高橋です。

> 最新版で確認致しましたが、現象は変わりませんでした。

ご確認ありがとうございます。

こちらでもSPP対応のBT機器を使用して検証しましたが、該当の現象は確認できませんでした。

Armadilloの個体不良の可能性がありますので、もし他の個体をお持ちでしたら個体を変えて確認してみてください。
個体不良の際はお手数なのですが、下記「製品保証について」に記載されている、
「保証交換のお申し込みの流れ」の手順をご参照のうえ、
手続きを行うこともご検討していただければと思います。
https://users.atmark-techno.com/support/warranty

また、ご使用になられているBT機器との相性問題も考えられます。
どのようなBT機器をご使用でしょうか。

hanako

2018年12月4日 17時24分

お世話になっております。

Armadilloは、他にもありますので、個体差を調査してみます。

> また、ご使用になられているBT機器との相性問題も考えられます。
> どのようなBT機器をご使用でしょうか。

BT機器は、マイクロストーン社の「MVP-RF8-HC」というものを使っています。

hanako

2018年12月10日 9時52分

お世話になっております。

> Armadilloは、他にもありますので、個体差を調査してみます。

他のArmadilloで確認しましたが、同様の現象が発生しました。
こちらで確認したArmadilloの型番は、2台ともAGX3142-D00Zです。

Dec 8 21:19:17 armadillo kernel: usb 2-1.3: USB disconnect, device number 4
Dec 8 21:19:17 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 8 21:19:17 armadillo systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Dec 8 21:19:17 armadillo systemd[1]: Stopped target Bluetooth.
Dec 8 21:19:18 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 8 21:19:18 armadillo kernel: usb 2-1.3: new full-speed USB device number 6 using ci_hdrc
Dec 8 21:19:18 armadillo kernel: usb 2-1.3: USB disconnect, device number 6
Dec 8 21:19:19 armadillo kernel: usb 2-1.3: new full-speed USB device number 7 using ci_hdrc
Dec 8 21:19:24 armadillo systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 8 21:19:24 armadillo systemd[1]: Reached target Bluetooth.
Dec 8 21:19:24 armadillo systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 8 21:19:24 armadillo bluetoothd[26956]: Failed to obtain handles for "Service Changed" characteristic
Dec 8 21:19:24 armadillo bluetoothd[26956]: Sap driver initialization failed.
Dec 8 21:19:24 armadillo bluetoothd[26956]: sap-server: Operation not permitted (1)

hanako

2018年12月14日 9時38分

お世話になっております。

本件ですが、現象発生時のBT機器でデータ収集のサンプリング周期を変更したところ、
通信が遮断されることはありませんでした。

もう少し、様子を見てみます。

何かお気づきの点がございましたら、ご教示ください。