New issue
Advanced search Search tips

Issue 676594 link

Starred by 3 users
Status: WontFix
Owner:
jlebel@chromium.org
Closed: Feb 2018
Components:
EstimatedDays: ----
NextAction: ----
OS: Mac
Pri: 3
Type: Bug



Sign in to add a comment

bluetooth: mac: SMP Pairing Request fails

Project Member Reported by fbeaufort@chromium.org, Dec 22 2016 
Google Chrome	57.0.2958.0 (Official Build) canary (64-bit)
OS	Mac OS X 
JavaScript	V8 5.7.307

What steps will reproduce the problem?
(1) Go to https://beaufortfrancois.github.io/samples/web-bluetooth/link-loss.html
(2) Make sure Wistiki BLE device is nearby and advertising its presence
(3) Click "Get Bluetooth Device Alert Level" button
(4) Pick device in chooser and click "Pair" button

What is the expected result?
It should read alert level value.

What happens instead?
Requesting Bluetooth Device...
Connecting to GATT Server...
Getting Link Loss Service...
Getting Alert Level Characteristic...
Reading Alert Level...
Argh! NotSupportedError: GATT operation failed for unknown reason.


Full logs from PacketLogger are attached. Here's the interesting part:

Dec 22 11:55:10.884	ATT Send	Wistiki-16687964462	0x0040	Read Request - Handle:0x0007
Dec 22 11:55:10.884	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff803c07d120 ****
Dec 22 11:55:10.913	ATT Receive	Wistiki-16687964462	0x0040	Read Response
Dec 22 11:55:10.914	HCI COMMAND	00:00:00:00:00:00	0x400E	[2013] LE Connection Update - Connection Handle: 0x0040
Dec 22 11:55:10.919	HCI EVENT	00:00:00:00:00:00	0x0000	Command Status - LE Connection Update
Dec 22 11:55:10.919	ATT Send	Wistiki-16687964462	0x0040	Read Request - Handle:0x0017
Dec 22 11:55:10.919	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff803c07d420 ****
Dec 22 11:55:10.928	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x00] Connection Update Req
Dec 22 11:55:10.944	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff803c07d1e0 for ACL packet 0xffffff80375bd800 -- numOutstandingHWPackets = 2, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:10.944	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff803c07e860 for ACL packet 0xffffff802817fa00 -- numOutstandingHWPackets = 2, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:10.958	ATT Receive	Wistiki-16687964462	0x0040	Error Response - Attribute Handle: 0x0017 - Error Code: 0x05
Dec 22 11:55:10.959	SMP Send	Wistiki-16687964462	0x0040	LE SMP Pairing Request Command 
Dec 22 11:55:10.959	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff803c07e860 ****
Dec 22 11:55:10.988	SMP Receive	Wistiki-16687964462	0x0040	LE SMP Pairing Response Command 
Dec 22 11:55:11.142	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff803c07d1e0 for ACL packet 0xffffff802817fa00 -- numOutstandingHWPackets = 1, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:11.143	SMP Send	Wistiki-16687964462	0x0040	LE SMP Pairing Confirm Command 
Dec 22 11:55:11.143	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff803c07d1e0 ****
Dec 22 11:55:11.988	POWER	00:00:00:00:00:00	0x0000	activityTickle
Dec 22 11:55:11.988	POWER	00:00:00:00:00:00	0x0000	[IOBluetoothHCIController][WakeUpDisplay] -- took 83981392 microseconds to call activityTickle()
Dec 22 11:55:12.024	HCI EVENT	Wistiki-16687964462	0x0040	LE Meta Event - LE Connection Update Complete
Dec 22 11:55:12.267	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff803c07e860 for ACL packet 0xffffff802cb4ca00 -- numOutstandingHWPackets = 1, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:13.268	SMP Receive	Wistiki-16687964462	0x0040	LE SMP Pairing Confirm Command 
Dec 22 11:55:13.269	SMP Send	Wistiki-16687964462	0x0040	LE SMP Pairing Random Command 
Dec 22 11:55:13.269	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff803c07d600 ****
Dec 22 11:55:14.642	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff803c07ed40 for ACL packet 0xffffff8038055300 -- numOutstandingHWPackets = 1, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:15.758	SMP Receive	Wistiki-16687964462	0x0040	LE SMP Pairing Random Command 
Dec 22 11:55:15.759	HCI COMMAND	00:00:00:00:00:00	0x401C	[2019] LE Start Encryption - Connection Handle: 0x0040
Dec 22 11:55:15.760	HCI EVENT	00:00:00:00:00:00	0x0000	Command Status - LE Start Encryption
Dec 22 11:55:17.003	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x03] Enc Req
Dec 22 11:55:18.248	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x04] Enc Rsp
Dec 22 11:55:20.738	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x05] Start Enc Req
Dec 22 11:55:21.983	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
Dec 22 11:55:22.303	ERROR	00:00:00:00:00:00	0x0000	An HCI Req 0X2019 timeout occurred -- missing com
Dec 22 11:55:22.303	SMP Send	Wistiki-16687964462	0x0040	LE SMP Pairing Failed Command - Reason Code: 0x08 - Unspecified Reason
Dec 22 11:55:22.303	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothHostControllerUSBTransport][BulkOutWriteCompleteAction] -- removed Bulk Out Write Timer 0xffffff802f1219e0 ****
Dec 22 11:55:24.474	HCI EVENT	Wistiki-16687964462	0x0040	Encryption Change Complete - Encryption Enabled - Connection Handle: 0x0040
Dec 22 11:55:24.474	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
Dec 22 11:55:25.894	NOTE	00:00:00:00:00:00	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- Handle 0x0040 -- remove ACL Timer 0xffffff802f120840 for ACL packet 0xffffff802bc69e00 -- numOutstandingHWPackets = 1, mNumNotDequeuedAckedPackets = 0, mDestroyDeviceCalled = FALSE -- hostController = 0xffffff8035db7000 ****
Dec 22 11:55:26.963	SMP Receive	Wistiki-16687964462	0x0040	LE SMP Encryption Information Command
Dec 22 11:55:28.208	LMP IN	B4:0D:A5:D9:E0:98	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x02] Terminate Ind - Error: 0x13
Dec 22 11:55:34.455	HCI EVENT	Wistiki-16687964462	0x0040	Disconnection Complete - Other End Terminated Connection: User Ended Connection (0x13) Connection Handle: 0x0040


It looks like the SMP Pairing Request is failing... ;(
wistiki-macos-packetlogger.txt
44.1 KB View Download

Comment 1 by ortuno@chromium.org, Jan 17 2017

Status: Available (was: Assigned)
Hmm this might be a problem with the device and how it interacts with the low level bluetooth stack. I don't think there is much we can do here :(
Project Member

Comment 2 by sheriffbot@chromium.org, Feb 15 2018

Labels: Hotlist-Recharge-Cold
Status: Untriaged (was: Available)
This issue has been Available for over a year. If it's no longer important or seems unlikely to be fixed, please consider closing it out. If it is important, please re-triage the issue.

Sorry for the inconvenience if the bug really should have been left as Available. If you change it back, also remove the "Hotlist-Recharge-Cold" label.

For more details visit https://www.chromium.org/issue-tracking/autotriage - Your friendly Sheriffbot

Comment 3 by reillyg@chromium.org, Feb 21 2018

Status: WontFix (was: Untriaged)
This seems infeasible given the device error.
►

Sign in to add a comment