This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

CC2541 HID mouse clicks won't work

I've integrated code from the HidKbM project into my own project and have successfully gotten much of it to work.  I can Send key presses, consumer control commands, and even move the mouse pointer around on the screen.  The remaining issues is that I can't seem to send mouse clicks (Macbook Pro 10.10.5).  I have run a bluetooth logger in the background and the computer seems to be receiving the mouse click commands, but no click event actually ever happens.

Does anyone have any thoughts as to what might cause this?

  • Hi Nathan,

    I have notified our HID team.
  • Slight update,
    I've been testing in Max OSX 10.10 which is where I've experienced the failures. Testing in windows 8 successfully produces mouse clicks. I'm not sure if anyone has experience with this.
  • Hi Nathan,

    I don't think we've tested the HID keyboard example against Mac OS, just Windows and Android. One would except HID descriptors are platform independent. Do you have any logs showing errors? BLE sniffer captures by any chance?

  • I've attached the log with a mouse down event at Mar 22 17:40:30.539 and a mouse up event at Mar 22 17:40:31.477.  There doesn't seem to be any particular errors that I can see.  

    Here's the extended information for mouse down event.  

    [Mar 22 17:40:30.539] [ATT Receive] Handle Value Notification - Handle:0x005c - Value:01 00 00 00
    Handle Value Notification - Handle:0x005c - Value:01 00 00 00
    Opcode: 0x1b
    Attribute Handle: 0x005c (92)
    [Mar 22 17:40:30.539] [L2CAP RECEIVE] Channel ID: 0x0004 Length: 0x0007 (07) [ 1B 5C 00 01 00 00 00 ]
    Channel ID: 0x0004 Length: 0x0007 (07) [ 1B 5C 00 01 00 00 00 ]
    00000000: 0700 0400 1b5c 0001 0000 00 .....\.....
    [Mar 22 17:40:30.539] [ACL RECEIVE] Data [Handle: 0x0041, Packet Boundary Flags: 0x2, Length: 0x000B (11)]
    Data [Handle: 0x0041, Packet Boundary Flags: 0x2, Length: 0x000B (11)]
    Packet Boundary Flags: [10] 0x02 - First packet of Higher Layer Message (i.e. start of an L2CAP packet)
    Broadcast Flags: [00] 0x00 - Point-to-point
    Data (0x000b bytes)
    [Mar 22 17:40:30.539] [ACL RECEIVE] 00000000: 4120 0b00 0700 0400 1b5c 0001 0000 00 A .......\.....
    00000000: 4120 0b00 0700 0400 1b5c 0001 0000 00 A .......\.....

    I assumed the issue might be due to the report map, but there's no mention in Apple's documentation that they require a specific report map.

    Mar 22 17:39:58.717	NOTE	0x0000	OS X Version 10.10.5 (Build 14F27) / Model ID: MacBookPro10,1
    Mar 22 17:39:58.718	NOTE	0x0000	Bluetooth Software Version: 4.3.6f3 16238
    Mar 22 17:39:58.718	NOTE	0x0000	Host Controller:  Broadcom / 0x05AC / 0x8286 / v150 c5836 / Built-In (Yes) / Location ID (0x1d181300)
    Mar 22 17:39:58.718	NOTE	0x0000	Support:  Deep Idle (No) / WoBT (Yes) / BTRS (No) / New Idle Policy (Yes) / Idle Time (0 ms)
    Mar 22 17:40:00.488	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -89 dBm - Type 9
    Mar 22 17:40:00.489	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -88 dBm
    Mar 22 17:40:00.761	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -85 dBm - Type 9
    Mar 22 17:40:00.762	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -85 dBm
    Mar 22 17:40:01.675	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm - Type 9
    Mar 22 17:40:01.676	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm
    Mar 22 17:40:02.583	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm - Type 9
    Mar 22 17:40:02.584	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm
    Mar 22 17:40:02.597	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -86 dBm
    Mar 22 17:40:04.960	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:04.962	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:04.975	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -79 dBm - Type 9
    Mar 22 17:40:04.976	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -80 dBm
    Mar 22 17:40:04.985	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:04.987	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:05.311	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:05.313	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:06.173	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -79 dBm - Type 9
    Mar 22 17:40:06.174	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -79 dBm
    Mar 22 17:40:06.179	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:06.181	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:06.475	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -62 dBm
    Mar 22 17:40:06.477	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -62 dBm
    Mar 22 17:40:06.760	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:06.762	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -52 dBm
    Mar 22 17:40:07.077	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.079	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.375	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -87 dBm
    Mar 22 17:40:07.377	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -87 dBm
    Mar 22 17:40:07.661	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.663	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.681	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.683	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - D4:F5:13:48:D5:0A  -53 dBm
    Mar 22 17:40:07.825	HCI EVENT	0x0041	LE Meta Event - LE Connection Complete - Status: 0x00 - Success - 0x0041 - Master - Public - D4:F5:13:48:D5:0A - Conn Interval: 15 ms
    Mar 22 17:40:07.827	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][IncrementActiveConnections] -- calling ChangeIdleTime (38356) 
    Mar 22 17:40:07.831	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:07.831	KERNEL DEBUG	0x0000	[0xffffff8067a4c000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:07.832	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:07.835	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:07.835	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:07.836	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:07.838	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:07.838	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:07.839	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:07.841	HCI COMMAND	0x411C	[2019] LE Start Encryption - Connection Handle: 0x0041
    Mar 22 17:40:07.841	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x2019 (LE Start Encryption) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:07.842	HCI EVENT	0x0000	Command Status - LE Start Encryption
    Mar 22 17:40:07.859	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:07.859	LMP IN	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x03] Enc Req
    Mar 22 17:40:07.874	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:07.874	LMP IN	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x04] Enc Rsp
    Mar 22 17:40:07.904	LMP IN	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x05] Start Enc Req
    Mar 22 17:40:07.904	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:07.934	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:07.934	LMP IN	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
    Mar 22 17:40:07.949	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:07.949	LMP IN	0x0000	[REC] Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
    Mar 22 17:40:07.949	HCI EVENT	0x0041	Encryption Change Complete - Encryption Enabled - Connection Handle: 0x0041
    Mar 22 17:40:07.949	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8064db3400, timer = 0xffffff806ff5c800
    Mar 22 17:40:07.949	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8064db3400, timer = 0xffffff806ff5c800
    Mar 22 17:40:07.949	ATT Send	0x0041	Exchange MTU Request - MTU:104
    Mar 22 17:40:07.953	HCI COMMAND	0x0000	[0C3F] Set AFH Host Channel Classification - Channel Map: 0xFF FF FF FF FF FF FF FF FF 7F
    Mar 22 17:40:07.953	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x0C3F (Set AFH Host Channel Classification) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:07.954	HCI EVENT	0x0000	[0C3F] Command Complete - Set AFH Host Channel Classification
    Mar 22 17:40:07.955	HCI COMMAND	0x0000	[2014] LE Set Host Channel Classification - Channel Map: 0x1FFFFFFFFF
    Mar 22 17:40:07.955	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x2014 (LE Set Host Channel Classification) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:07.957	HCI EVENT	0x0000	[2014] Command Complete - LE Set Host Channel Classification
    Mar 22 17:40:07.979	ATT Receive	0x0041	Exchange MTU Response - MTU:23
    Mar 22 17:40:07.979	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80657c4700, timer = 0xffffff806e3b7880
    Mar 22 17:40:07.979	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80657c4700, timer = 0xffffff806e3b7880
    Mar 22 17:40:07.979	ATT Send	0x0041	Read By Type Request - Start Handle:0x0001 - End Handle:0xffff - UUID:2A00 (Device Name)
    Mar 22 17:40:07.995	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:07.995	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8064db3400, aclPacket->ACLTimer = 0xffffff806ff5c800 ****
    Mar 22 17:40:07.995	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80657c4700, aclPacket->ACLTimer = 0xffffff806e3b7880 ****
    Mar 22 17:40:08.024	ATT Receive	0x0041	Read By Type Response
    Mar 22 17:40:08.035	HCI COMMAND	0x0000	[200E] LE Create Connection Cancel
    Mar 22 17:40:08.035	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200E (LE Create Connection Cancel) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:08.036	HCI EVENT	0x0000	[200E] Command Complete - LE Create Connection Cancel - Status: 0x0C 
    Mar 22 17:40:08.042	HCI COMMAND	0x0000	[2012] LE Remove Device From White List - D4:F5:13:48:D5:0A
    Mar 22 17:40:08.042	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x2012 (LE Remove Device From White List) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:08.043	HCI EVENT	0x0000	[2012] Command Complete - LE Remove Device From White List
    Mar 22 17:40:08.045	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SetIdleTimerValue] -- newValue = 0, mDesiredIdleTimerValue = 0 -- this = 0xffffff8045a28800 ****
    Mar 22 17:40:08.045	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][ChangeIdleTime] -- No change -- idle time (0 milliseconds) is the same -- this = 0xffffff8045a28800 ****
    Mar 22 17:40:08.047	HCI COMMAND	0x410E	[2013] LE Connection Update - Connection Handle: 0x0041
    Mar 22 17:40:08.047	KERNEL DEBUG	0x0000	[0xffffff8067abc000] [IOBluetoothHCIRequest][Start] --  OpCode 0x2013 (LE Connection Update) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:08.053	HCI EVENT	0x0000	Command Status - LE Connection Update
    Mar 22 17:40:08.053	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8056685200, timer = 0xffffff80735ec880
    Mar 22 17:40:08.053	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8056685200, timer = 0xffffff80735ec880
    Mar 22 17:40:08.053	ATT Send	0x0041	Write Request - Handle:0x0018
    Mar 22 17:40:08.054	LMP IN	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x00] Connection Update Req
    Mar 22 17:40:08.054	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:08.084	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.084	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff805c317c00, timer = 0xffffff806ce7ec80
    Mar 22 17:40:08.084	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff805c317c00, timer = 0xffffff806ce7ec80
    Mar 22 17:40:08.084	ATT Send	0x0041	Write Request - Handle:0x005d
    Mar 22 17:40:08.100	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.100	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8056685200, aclPacket->ACLTimer = 0xffffff80735ec880 ****
    Mar 22 17:40:08.100	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff805c317c00, aclPacket->ACLTimer = 0xffffff806ce7ec80 ****
    Mar 22 17:40:08.114	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.114	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8056685200, timer = 0xffffff806b615080
    Mar 22 17:40:08.114	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8056685200, timer = 0xffffff806b615080
    Mar 22 17:40:08.114	ATT Send	0x0041	Write Request - Handle:0x0061
    Mar 22 17:40:08.144	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.144	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80473fba00, timer = 0xffffff806602af00
    Mar 22 17:40:08.144	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80473fba00, timer = 0xffffff806602af00
    Mar 22 17:40:08.144	ATT Send	0x0041	Write Request - Handle:0x0065
    Mar 22 17:40:08.159	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.159	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8056685200, aclPacket->ACLTimer = 0xffffff806b615080 ****
    Mar 22 17:40:08.159	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80473fba00, aclPacket->ACLTimer = 0xffffff806602af00 ****
    Mar 22 17:40:08.174	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.527	HCI EVENT	0x0041	LE Meta Event - LE Connection Update Complete
    Mar 22 17:40:08.546	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804a077100, timer = 0xffffff8058ffc680
    Mar 22 17:40:08.546	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804a077100, timer = 0xffffff8058ffc680
    Mar 22 17:40:08.546	ATT Send	0x0041	Read Request - Handle:0x0021
    Mar 22 17:40:08.601	ATT Receive	0x0041	Read Response
    Mar 22 17:40:08.602	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff805eac6100, timer = 0xffffff8066be8b00
    Mar 22 17:40:08.602	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff805eac6100, timer = 0xffffff8066be8b00
    Mar 22 17:40:08.602	ATT Send	0x0041	Write Request - Handle:0x002f
    Mar 22 17:40:08.634	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -85 dBm - Type 9
    Mar 22 17:40:08.635	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm
    Mar 22 17:40:08.640	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.640	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804a077100, aclPacket->ACLTimer = 0xffffff8058ffc680 ****
    Mar 22 17:40:08.640	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff805eac6100, aclPacket->ACLTimer = 0xffffff8066be8b00 ****
    Mar 22 17:40:08.676	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.677	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8059de3400, timer = 0xffffff805b688a00
    Mar 22 17:40:08.677	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8059de3400, timer = 0xffffff805b688a00
    Mar 22 17:40:08.677	ATT Send	0x0041	Write Request - Handle:0x0049
    Mar 22 17:40:08.751	ATT Receive	0x0041	Write Response
    Mar 22 17:40:08.752	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8059d93800, timer = 0xffffff80652f5d80
    Mar 22 17:40:08.752	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8059d93800, timer = 0xffffff80652f5d80
    Mar 22 17:40:08.752	ATT Send	0x0041	Read Request - Handle:0x0048
    Mar 22 17:40:08.789	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.789	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8059de3400, aclPacket->ACLTimer = 0xffffff805b688a00 ****
    Mar 22 17:40:08.789	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8059d93800, aclPacket->ACLTimer = 0xffffff80652f5d80 ****
    Mar 22 17:40:08.826	ATT Receive	0x0041	Read Response
    Mar 22 17:40:08.827	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8059de3400, timer = 0xffffff8066600200
    Mar 22 17:40:08.827	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8059de3400, timer = 0xffffff8066600200
    Mar 22 17:40:08.827	ATT Send	0x0041	Write Command - Handle:0x002e
    Mar 22 17:40:08.827	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff806e174a00, timer = 0xffffff80652f5d80
    Mar 22 17:40:08.827	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff806e174a00, timer = 0xffffff80652f5d80
    Mar 22 17:40:08.827	ATT Send	0x0041	Read Request - Handle:0x0048
    Mar 22 17:40:08.903	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.903	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8059de3400, aclPacket->ACLTimer = 0xffffff8066600200 ****
    Mar 22 17:40:08.903	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff806e174a00, aclPacket->ACLTimer = 0xffffff80652f5d80 ****
    Mar 22 17:40:08.922	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -92 dBm - Type 9
    Mar 22 17:40:08.923	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -90 dBm
    Mar 22 17:40:08.939	ATT Receive	0x0041	Read Response
    Mar 22 17:40:08.939	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff806691ff00, timer = 0xffffff8074000000
    Mar 22 17:40:08.939	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff806691ff00, timer = 0xffffff8074000000
    Mar 22 17:40:08.939	ATT Send	0x0041	Write Command - Handle:0x002e
    Mar 22 17:40:08.939	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80460e6400, timer = 0xffffff8046443f00
    Mar 22 17:40:08.939	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80460e6400, timer = 0xffffff8046443f00
    Mar 22 17:40:08.939	ATT Send	0x0041	Write Command - Handle:0x002e
    Mar 22 17:40:08.940	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:82 23 00 05 90 A1 01 14 00 03 00 10 FF 00 00 00 00 42 65 61 
    Mar 22 17:40:08.940	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:01 6E 2B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 BC 
    Mar 22 17:40:08.941	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:00 4C 
    Mar 22 17:40:08.977	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:A2 23 00 10 83 12 26 00 00 8C 5F 54 1D 63 E5 F1 56 08 68 69 
    Mar 22 17:40:08.977	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0002  
    Mar 22 17:40:08.977	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff806691ff00, aclPacket->ACLTimer = 0xffffff8074000000 ****
    Mar 22 17:40:08.977	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80460e6400, aclPacket->ACLTimer = 0xffffff8046443f00 ****
    Mar 22 17:40:08.977	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:21 64 5F 74 65 73 74 73 44 65 6D 6F 00 00 00 00 00 00 00 D2 
    Mar 22 17:40:08.978	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:20 81 
    Mar 22 17:40:09.014	ATT Receive	0x0041	Handle Value Notification - Handle:0x002e - Value:C0 03 00 20 A1 01 76 47 
    Mar 22 17:40:09.825	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:09.825	KERNEL DEBUG	0x0000	[0xffffff806b2d0000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:09.826	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:10.878	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:10.878	KERNEL DEBUG	0x0000	[0xffffff806be46000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:10.879	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:11.245	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x00, Filter duplicates: 0
    Mar 22 17:40:11.245	KERNEL DEBUG	0x0000	[0xffffff806b2d0000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.246	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.250	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.250	KERNEL DEBUG	0x0000	[0xffffff806b2d0000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.251	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.252	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    Mar 22 17:40:11.252	KERNEL DEBUG	0x0000	[0xffffff806b525000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.253	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:11.253	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:11.253	KERNEL DEBUG	0x0000	[0xffffff806b525000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.254	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 17
    Mar 22 17:40:11.255	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.255	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.256	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.256	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1001 - LE Resolve Address Whitelist - LE Allow Scatternet
    Mar 22 17:40:11.256	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.257	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    Mar 22 17:40:11.257	HCI COMMAND	0x0000	[FD47] Vendor Specific Command - LE Extended Duplicate Filter - Extended Duplicate Filter - Mode: Disable
    Mar 22 17:40:11.257	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.258	HCI EVENT	0x0000	[FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    Mar 22 17:40:11.258	HCI COMMAND	0x0000	[200B] LE Set Scan Parameters - Active - 30/300 (ms)
    Mar 22 17:40:11.258	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200B (LE Set Scan Parameters) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.259	HCI EVENT	0x0000	[200B] Command Complete - LE Set Scan Parameters
    Mar 22 17:40:11.259	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x01, Filter duplicates: 0
    Mar 22 17:40:11.259	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.260	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.263	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x00, Filter duplicates: 0
    Mar 22 17:40:11.263	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.265	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.268	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.268	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.269	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.270	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    Mar 22 17:40:11.270	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.271	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:11.271	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:11.271	KERNEL DEBUG	0x0000	[0xffffff804b98e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.272	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 17
    Mar 22 17:40:11.273	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.273	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.274	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.274	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1001 - LE Resolve Address Whitelist - LE Allow Scatternet
    Mar 22 17:40:11.274	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.275	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    Mar 22 17:40:11.275	HCI COMMAND	0x0000	[FD47] Vendor Specific Command - LE Extended Duplicate Filter - Extended Duplicate Filter - Mode: Disable
    Mar 22 17:40:11.275	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.276	HCI EVENT	0x0000	[FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    Mar 22 17:40:11.276	HCI COMMAND	0x0000	[200B] LE Set Scan Parameters - Active - 30/300 (ms)
    Mar 22 17:40:11.276	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200B (LE Set Scan Parameters) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.277	HCI EVENT	0x0000	[200B] Command Complete - LE Set Scan Parameters
    Mar 22 17:40:11.277	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x01, Filter duplicates: 0
    Mar 22 17:40:11.277	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.278	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.283	HCI COMMAND	0x0000	[0401] HCI Inquiry
    Mar 22 17:40:11.283	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0x0401 (HCI Inquiry) from: blued (84)  Asynchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 43210
    Mar 22 17:40:11.284	HCI EVENT	0x0000	Command Status - HCI Inquiry
    Mar 22 17:40:11.290	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x00, Filter duplicates: 0
    Mar 22 17:40:11.290	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.296	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.300	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.300	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.301	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.302	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    Mar 22 17:40:11.302	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.303	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:11.303	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:11.303	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.304	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 17
    Mar 22 17:40:11.305	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.305	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.306	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.306	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1001 - LE Resolve Address Whitelist - LE Allow Scatternet
    Mar 22 17:40:11.306	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.307	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    Mar 22 17:40:11.307	HCI COMMAND	0x0000	[FD47] Vendor Specific Command - LE Extended Duplicate Filter - Extended Duplicate Filter - Mode: Disable
    Mar 22 17:40:11.307	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.308	HCI EVENT	0x0000	[FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    Mar 22 17:40:11.308	HCI COMMAND	0x0000	[200B] LE Set Scan Parameters - Active - 30/300 (ms)
    Mar 22 17:40:11.308	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200B (LE Set Scan Parameters) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.309	HCI EVENT	0x0000	[200B] Command Complete - LE Set Scan Parameters
    Mar 22 17:40:11.309	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x01, Filter duplicates: 0
    Mar 22 17:40:11.309	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.310	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.313	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x00, Filter duplicates: 0
    Mar 22 17:40:11.313	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.314	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.318	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.318	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.319	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.320	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    Mar 22 17:40:11.320	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.321	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:11.321	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:11.321	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.322	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 17
    Mar 22 17:40:11.323	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    Mar 22 17:40:11.323	KERNEL DEBUG	0x0000	[0xffffff806cc0e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.324	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 19, Remaining: 19
    Mar 22 17:40:11.324	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1001 - LE Resolve Address Whitelist - LE Allow Scatternet
    Mar 22 17:40:11.324	KERNEL DEBUG	0x0000	[0xffffff806cc0e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.325	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    Mar 22 17:40:11.325	HCI COMMAND	0x0000	[FD47] Vendor Specific Command - LE Extended Duplicate Filter - Extended Duplicate Filter - Mode: Disable
    Mar 22 17:40:11.325	KERNEL DEBUG	0x0000	[0xffffff806cc0e000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.326	HCI EVENT	0x0000	[FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    Mar 22 17:40:11.326	HCI COMMAND	0x0000	[200B] LE Set Scan Parameters - Active - 30/300 (ms)
    Mar 22 17:40:11.326	KERNEL DEBUG	0x0000	[0xffffff806cc0e000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200B (LE Set Scan Parameters) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.327	HCI EVENT	0x0000	[200B] Command Complete - LE Set Scan Parameters
    Mar 22 17:40:11.327	HCI COMMAND	0x0000	[200C] LE Set Scan Enable - 0x01, Filter duplicates: 0
    Mar 22 17:40:11.327	KERNEL DEBUG	0x0000	[0xffffff806cc0e000] [IOBluetoothHCIRequest][Start] --  OpCode 0x200C (LE Set Scan Enable) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.328	HCI EVENT	0x0000	[200C] Command Complete - LE Set Scan Enable
    Mar 22 17:40:11.623	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Remove Matching Rule with Address for Type: 9
    Mar 22 17:40:11.623	KERNEL DEBUG	0x0000	[0xffffff806bf9c000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.624	HCI EVENT	0x0000	[FCE9] Command Complete - Status: 0x0C Broadcom VSC Event - LE Meta VSC: Reserved - 0xD0
    Mar 22 17:40:11.624	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:11.624	KERNEL DEBUG	0x0000	[0xffffff806bf9c000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:11.625	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:11.910	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:11.910	KERNEL DEBUG	0x0000	[0xffffff804a9cd000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:11.911	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:12.839	L2CAP RECEIVE	0x0041	Connection Parameter Update Request
    Mar 22 17:40:12.839	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80654fdc00, timer = 0xffffff806be9f600
    Mar 22 17:40:12.839	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80654fdc00, timer = 0xffffff806be9f600
    Mar 22 17:40:12.839	L2CAP SEND	0x0041	Connection Parameter Update Response
    Mar 22 17:40:12.840	HCI COMMAND	0x410E	[2013] LE Connection Update - Connection Handle: 0x0041
    Mar 22 17:40:12.840	KERNEL DEBUG	0x0000	[0xffffff806bf9a000] [IOBluetoothHCIRequest][Start] --  OpCode 0x2013 (LE Connection Update) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:12.845	HCI EVENT	0x0000	Command Status - LE Connection Update
    Mar 22 17:40:12.877	KERNEL DEBUG	0x0000	**** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    Mar 22 17:40:12.877	LMP IN	0x0000	[SEND]Low Energy LL Control PDU LMP Message - [0x00] Connection Update Req
    Mar 22 17:40:12.898	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:12.898	KERNEL DEBUG	0x0000	[0xffffff8053b3c000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:12.899	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:13.089	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0001  
    Mar 22 17:40:13.089	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff80654fdc00, aclPacket->ACLTimer = 0xffffff806be9f600 ****
    Mar 22 17:40:13.140	HCI EVENT	0x0041	LE Meta Event - LE Connection Update Complete
    Mar 22 17:40:13.898	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:13.898	KERNEL DEBUG	0x0000	[0xffffff8053b3c000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:13.899	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:14.049	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -90 dBm - Type 9
    Mar 22 17:40:14.050	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -91 dBm
    Mar 22 17:40:14.897	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:14.897	KERNEL DEBUG	0x0000	[0xffffff806c862000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:14.899	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:15.519	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -95 dBm - Type 9
    Mar 22 17:40:15.520	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -93 dBm
    Mar 22 17:40:15.897	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:15.897	KERNEL DEBUG	0x0000	[0xffffff806afed000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:15.898	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:16.121	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -86 dBm
    Mar 22 17:40:16.139	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -85 dBm - Type 9
    Mar 22 17:40:16.875	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:16.875	KERNEL DEBUG	0x0000	[0xffffff806bf9c000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:16.876	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:17.027	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -86 dBm
    Mar 22 17:40:17.865	ATT Receive	0x0041	Find By Type Value Request
    Mar 22 17:40:17.865	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804954a200, timer = 0xffffff805c538880
    Mar 22 17:40:17.865	KERNEL DEBUG	0x0000	**** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804954a200, timer = 0xffffff805c538880
    Mar 22 17:40:17.865	ATT Send	0x0041	Error Response - Attribute Handle: 0x0001 - Error Code: 0x0A
    Mar 22 17:40:17.889	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:17.889	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:17.890	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:18.090	HCI EVENT	0x0041	Number of Completed Packets - Connection Handle: 0x0041 - Packets: 0x0001  
    Mar 22 17:40:18.090	NOTE	0x0000	**** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8045a28800, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff804954a200, aclPacket->ACLTimer = 0xffffff805c538880 ****
    Mar 22 17:40:18.528	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -82 dBm - Type 9
    Mar 22 17:40:18.529	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -82 dBm
    Mar 22 17:40:18.816	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -78 dBm - Type 9
    Mar 22 17:40:18.817	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -79 dBm
    Mar 22 17:40:18.890	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:18.890	KERNEL DEBUG	0x0000	[0xffffff8053b3c000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:18.891	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:19.444	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -80 dBm - Type 9
    Mar 22 17:40:19.445	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -80 dBm
    Mar 22 17:40:19.731	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -76 dBm - Type 9
    Mar 22 17:40:19.732	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -76 dBm
    Mar 22 17:40:19.891	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:19.891	KERNEL DEBUG	0x0000	[0xffffff806afed000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:19.892	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:20.890	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:20.890	KERNEL DEBUG	0x0000	[0xffffff806bf9a000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:20.892	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:21.557	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -78 dBm - Type 9
    Mar 22 17:40:21.890	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:21.890	KERNEL DEBUG	0x0000	[0xffffff8069a2f000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:21.891	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:22.734	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -91 dBm - Type 9
    Mar 22 17:40:22.735	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -93 dBm
    Mar 22 17:40:22.877	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:22.877	KERNEL DEBUG	0x0000	[0xffffff80480bd000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:22.878	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:22.958	HCI EVENT	0x0000	Inquiry Result - EIR -  78:CA:39:C1:09:C4 - Computer : Desktop - RSSI: -81 dBm
    Mar 22 17:40:23.886	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:23.886	KERNEL DEBUG	0x0000	[0xffffff804f595000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:23.887	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:23.936	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -88 dBm - Type 9
    Mar 22 17:40:24.087	HCI EVENT	0x0000	Inquiry Complete - 
    Mar 22 17:40:24.880	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:24.880	KERNEL DEBUG	0x0000	[0xffffff804a1c3000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:24.881	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:25.892	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:25.892	KERNEL DEBUG	0x0000	[0xffffff806be46000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:25.893	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:26.030	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -91 dBm - Type 9
    Mar 22 17:40:26.031	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -91 dBm
    Mar 22 17:40:26.698	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Remove Matching Rule with Address for Type: 9
    Mar 22 17:40:26.698	KERNEL DEBUG	0x0000	[0xffffff80498df000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:26.699	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Remove Matching Rule with Address - Capacity: 19, Remaining: 19
    Mar 22 17:40:26.699	HCI COMMAND	0x0000	[FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    Mar 22 17:40:26.699	KERNEL DEBUG	0x0000	[0xffffff80498df000] [IOBluetoothHCIRequest][Start] --  OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    Mar 22 17:40:26.700	HCI EVENT	0x0000	[FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 19, Remaining: 18
    Mar 22 17:40:26.894	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:26.894	KERNEL DEBUG	0x0000	[0xffffff80498df000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:26.895	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:27.852	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -86 dBm - Type 9
    Mar 22 17:40:27.853	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -87 dBm
    Mar 22 17:40:27.889	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:27.889	KERNEL DEBUG	0x0000	[0xffffff806b2e1000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:27.891	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:28.138	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -90 dBm - Type 9
    Mar 22 17:40:28.139	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -90 dBm
    Mar 22 17:40:28.886	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:28.886	KERNEL DEBUG	0x0000	[0xffffff806b2e1000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:28.888	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:29.321	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -84 dBm - Type 9
    Mar 22 17:40:29.322	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -84 dBm
    Mar 22 17:40:29.896	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:29.896	KERNEL DEBUG	0x0000	[0xffffff806b525000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:29.897	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:30.516	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -90 dBm - Type 9
    Mar 22 17:40:30.539	ATT Receive	0x0041	Handle Value Notification - Handle:0x005c - Value:01 00 00 00 
    Mar 22 17:40:30.895	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:30.895	KERNEL DEBUG	0x0000	[0xffffff804e589000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:30.897	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:31.147	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -84 dBm - Type 9
    Mar 22 17:40:31.148	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -85 dBm
    Mar 22 17:40:31.426	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -88 dBm - Type 9
    Mar 22 17:40:31.427	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -89 dBm
    Mar 22 17:40:31.477	ATT Receive	0x0041	Handle Value Notification - Handle:0x005c - Value:00 00 00 00 
    Mar 22 17:40:31.870	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:31.870	KERNEL DEBUG	0x0000	[0xffffff80498df000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:31.872	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:32.886	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:32.886	KERNEL DEBUG	0x0000	[0xffffff80628b8000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:32.887	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:32.924	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -86 dBm
    Mar 22 17:40:33.525	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -81 dBm - Type 9
    Mar 22 17:40:33.526	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -82 dBm
    Mar 22 17:40:33.889	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:33.889	KERNEL DEBUG	0x0000	[0xffffff804ebd9000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:33.890	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:34.444	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -80 dBm - Type 9
    Mar 22 17:40:34.445	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -81 dBm
    Mar 22 17:40:34.743	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -85 dBm
    Mar 22 17:40:34.883	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:34.883	KERNEL DEBUG	0x0000	[0xffffff804f595000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:34.884	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:35.626	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 18:EE:69:1F:AC:C5  -80 dBm - Type 9
    Mar 22 17:40:35.647	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 1 - 75:F1:8E:75:0F:04  -86 dBm
    Mar 22 17:40:35.898	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:35.898	KERNEL DEBUG	0x0000	[0xffffff806b525000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:35.899	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:36.825	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -88 dBm - Type 9
    Mar 22 17:40:36.826	HCI EVENT	0x0000	LE Meta Event - LE Advertising Report - 0 - 60:03:08:AE:D8:B1  -88 dBm
    Mar 22 17:40:36.896	HCI COMMAND	0x0041	[1405] Read RSSI - Connection Handle: 0x0041
    Mar 22 17:40:36.896	KERNEL DEBUG	0x0000	[0xffffff804e589000] [IOBluetoothHCIRequest][Start] --  OpCode 0x1405 (Read RSSI) from: blued (84)  Synchronous  status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    Mar 22 17:40:36.897	HCI EVENT	0x0000	[1405] Command Complete - Read RSSI
    Mar 22 17:40:37.059	NOTE	0x0000	Disconnected from OS X Device