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 advertising but unable to connect

Other Parts Discussed in Thread: CC2541, CC2540

Hello all,

I have a project based on the BLE bridge project. My CC2541 was set up to advertise as a peripheral immediately on bootup, and I wanted to change it to advertise after a button press (like the keyfob project).

I set up the GAP during initialization as follows:

 // Setup the GAP Peripheral Role Profile

  {

    uint8 initial_advertising_enable = FALSE;

    // By setting this to zero, the device will go into the waiting state after

    // being discoverable for 30.72 second, and will not being advertising again

    // until the enabler is set back to TRUE

    uint16 gapRole_AdvertOffTime = 0;



    uint8 enable_update_request = DEFAULT_ENABLE_UPDATE_REQUEST;

    uint16 desired_min_interval = DEFAULT_DESIRED_MIN_CONN_INTERVAL;

    uint16 desired_max_interval = DEFAULT_DESIRED_MAX_CONN_INTERVAL;

    uint16 desired_slave_latency = DEFAULT_DESIRED_SLAVE_LATENCY;

    uint16 desired_conn_timeout = DEFAULT_DESIRED_CONN_TIMEOUT;



    // Set the GAP Role Parameters

    GAPRole_SetParameter( GAPROLE_ADVERT_ENABLED, sizeof( uint8 ), &initial_advertising_enable );

    GAPRole_SetParameter( GAPROLE_ADVERT_OFF_TIME, sizeof( uint16 ), &gapRole_AdvertOffTime );



    GAPRole_SetParameter( GAPROLE_SCAN_RSP_DATA, sizeof ( scanData ), scanData );

    GAPRole_SetParameter( GAPROLE_ADVERT_DATA, sizeof( advData ), advData );



    GAPRole_SetParameter( GAPROLE_PARAM_UPDATE_ENABLE, sizeof( uint8 ), &enable_update_request );

    GAPRole_SetParameter( GAPROLE_MIN_CONN_INTERVAL, sizeof( uint16 ), &desired_min_interval );

    GAPRole_SetParameter( GAPROLE_MAX_CONN_INTERVAL, sizeof( uint16 ), &desired_max_interval );

    GAPRole_SetParameter( GAPROLE_SLAVE_LATENCY, sizeof( uint16 ), &desired_slave_latency );

    GAPRole_SetParameter( GAPROLE_TIMEOUT_MULTIPLIER, sizeof( uint16 ), &desired_conn_timeout );

  }

  // Set the GAP Characteristics

  GGS_SetParameter( GGS_DEVICE_NAME_ATT, GAP_DEVICE_NAME_LEN, attDeviceName );

  // Setup the GAP Bond Manager

  {

    uint32 passkey = DEFAULT_PASSCODE;

    uint8 pairMode = DEFAULT_PAIRING_MODE;

    uint8 mitm = DEFAULT_MITM_MODE;

    uint8 ioCap = DEFAULT_IO_CAPABILITIES;

    uint8 bonding = DEFAULT_BONDING_MODE;

    GAPBondMgr_SetParameter( GAPBOND_DEFAULT_PASSCODE, sizeof( uint32 ), &passkey );

    GAPBondMgr_SetParameter( GAPBOND_PAIRING_MODE, sizeof( uint8 ), &pairMode );

    GAPBondMgr_SetParameter( GAPBOND_MITM_PROTECTION, sizeof( uint8 ), &mitm );

    GAPBondMgr_SetParameter( GAPBOND_IO_CAPABILITIES, sizeof( uint8 ), &ioCap );

    GAPBondMgr_SetParameter( GAPBOND_BONDING_ENABLED, sizeof( uint8 ), &bonding );

  }

#if defined FEATURE_OAD 

  VOID OADTarget_AddService(); // OAD Profile 

#endif


  // Simple GATT Profile

  DataProfile_AddService( GATT_ALL_SERVICES );  
  

  // Setup the SimpleProfile Characteristic Values

  {

    uint8 charValue1 = 1;

    uint8 charValue2 = 2;

    DataProfile_SetParameter( DATAPROFILE_CHAR1, sizeof ( uint8 ), &charValue1 );

    DataProfile_SetParameter( DATAPROFILE_CHAR2, sizeof ( uint8 ), &charValue2 );

  }  

  // Register callback with SimpleGATTprofile

  VOID DataProfile_Register( &DataProfileCBs ); 


  // Set up HID keyboard service

  HidKbd_AddService( );

  // Register for HID Dev callback

  TBHidDev_Register( &hidEmuKbdCfg, &BLE_BridgeCBs );


  //disable halt during RF (needed for UART / SPI)

  HCI_EXT_HaltDuringRfCmd(HCI_EXT_HALT_DURING_RF_DISABLE);


  // Setup a delayed profile startup

  osal_set_event( BLE_Bridge_TaskID, SBP_START_DEVICE_EVT );

}

 

On the button press event handling, I use the following:

         UINT8 gapRoleState;

         GAPRole_GetParameter(GAPROLE_STATE, &gapRoleState);

         if (gapRoleState!=GAPROLE_CONNECTED)

         {

           UINT8 current_adv_enabled_status;

           UINT8 new_adv_enabled_status = TRUE;

           GAPRole_GetParameter(GAPROLE_ADVERT_ENABLED, &current_adv_enabled_status);

           if (current_adv_enabled_status==FALSE)

           {

             UINT8 pairMode = GAPBOND_PAIRING_MODE_WAIT_FOR_REQ;

              GAPRole_SetParameter( GAPROLE_ADVERT_ENABLED, sizeof( uint8 ), &new_adv_enabled_status );

              GAPBondMgr_SetParameter(GAPBOND_PAIRING_MODE, sizeof( uint8 ), &pairMode);

           }

         }

The results are, I can see the unit broadcasting for 30sec after the button is pressed, but when I try to connect or pair from an iOS device, there is no response from the CC2541. However, if I set up initial_advertising_enable=TRUE in my initialization then even after several minutes I am able to press a key, advertise, and connect to the device. 

This makes me feel that setting initial_advertising_enable= FALSE during initialization is causing some of the callbacks to be improperly set up, so that the CC2541 is unable to respond to the link request from the iOS device. 

However, I have looked at the keyfob project and cannot see what I am leaving out.

Does anyone know if there is another critical part of the linking process that I need to, and have not, changed?

Thanks,

Sam

  • Hi Sam,

    It would help if you can provide a sniffer trace showing the working & non-working case.

    Are you attempting to connect from the iOS device after the 30 sec timeout?

    Best wishes
  • The way things are set up right now, we are constantly advertising because the event that triggers us to advertise if not already advertising is hit more frequently than every 30 seconds, in the working and non-working cases. The difference is, in the working case the initialization sets advertising to true before we start the device, and in the failing case the advertising is set to true after we start the device.

    At the bottom of this reply are sniffer traces of trying to connect from a Mac OSX Yosemite.
    Things look the same in both cases until we start trying to add the device to the white list.

    (Also, I cannot tell if the events in the working case are happening as they should, even if it is functional - is there documentation you can point me to that shows what a good and efficient link establishing packet sniff looks like?)

    Thanks in advance,
    Sam


    non-working case:

    [Mar 25 14:03:09.016] [HCI COMMAND] [2011] LE Add Device To White List - D0:07:FF:FF:FF:FF
    [Mar 25 14:03:09.016] [KERNEL DEBUG] [0xffffff8047fe3000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2011 (LE Add Device To White List) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 14:03:09.016] [HCI EVENT] LE Meta Event - LE Connection Complete - Status: 0x02 - Connection Handle: 0x0040
    [Mar 25 14:03:09.017] [HCI EVENT] [2011] Command Complete - LE Add Device To White List
    [Mar 25 14:03:09.019] [HCI COMMAND] [200D] LE Create Connection - 00:00:00:00:00:00
    [Mar 25 14:03:09.019] [KERNEL DEBUG] [0xffffff8045874000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200D (LE Create Connection) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 14:03:09.022] [HCI EVENT] Command Status - LE Create Connection
    [Mar 25 14:03:09.023] [KERNEL DEBUG] **** [IOBluetoothHCIController][SetIdleTimerValue] -- newValue = 0, mDesiredIdleTimerValue = 0 -- this = 0xffffff8039b56000 ****
    [Mar 25 14:03:09.023] [KERNEL DEBUG] **** [IOBluetoothHCIController][ChangeIdleTime] -- No change -- idle time (0 milliseconds) is the same -- this = 0xffffff8039b56000 ****
    [Mar 25 14:03:09.073] [HCI EVENT] LE Meta Event - LE Connection Complete - Status: 0x00 - Connection Handle: 0x0040
    [Mar 25 14:03:09.074] [KERNEL DEBUG] **** [IOBluetoothHCIController][IncrementActiveConnections] -- calling ChangeIdleTime (38356)
    [Mar 25 14:03:09.077] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 14:03:09.077] [KERNEL DEBUG] [0xffffff8048093000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 14:03:09.077] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 14:03:09.081] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 14:03:09.081] [KERNEL DEBUG] [0xffffff8047fe4000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 14:03:09.082] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 14:03:09.084] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 14:03:09.084] [KERNEL DEBUG] [0xffffff8047fe4000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 14:03:09.084] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 14:03:09.090] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80482a5400, timer = 0xffffff8047bba380
    [Mar 25 14:03:09.090] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80482a5400, timer = 0xffffff8047bba380
    [Mar 25 14:03:09.090] [ATT Send] Exchange MTU Request - MTU:104
    [Mar 25 14:03:09.096] [HCI EVENT] LE Meta Event - LE Advertising Report - 0 - D0:07:FF:FF:FF:FF -64 dBm
    [Mar 25 14:03:09.216] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 14:03:09.216] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff80482a5400, aclPacket->ACLTimer = 0xffffff8047bba380 ****
    [Mar 25 14:03:09.238] [HCI EVENT] Disconnection Complete - Connection Failed To Be Established (0x3E) Connection Handle: 0x0040
    [Mar 25 14:03:09.238] [KERNEL DEBUG] **** [IOBluetoothHCIController][DecrementActiveConnections] -- calling ChangeIdleTime (500 ms)
    [Mar 25 14:03:09.238] [KERNEL DEBUG] Bulk In Pipe Aborted
    [Mar 25 14:03:09.238] [KERNEL DEBUG] **** [IOBluetoothHostControllerUSBTransport][StopBulkPipeRead] -- mInt0BulkInPipe->Abort() returned 0x0000 (kIOReturnSuccess) ****
    [Mar 25 14:03:09.238] [KERNEL DEBUG] **** [IOBluetoothHCIController][SetIdleTimerValue] -- newValue = 500, mDesiredIdleTimerValue = 500 -- this = 0xffffff8039b56000 ****
    [Mar 25 14:03:09.238] [KERNEL DEBUG] **** [IOBluetoothHCIController][ChangeIdleTime] -- User manually changed the idle timer value -- Idle Timer was stopped -- calling StartIdleTimer (500 milliseconds) -- this = 0xffffff8039b56000 ****



    working case:

    [Mar 25 13:33:17.947] [HCI COMMAND] [2011] LE Add Device To White List - AB:03:FF:FF:BB:DD
    [Mar 25 13:33:17.947] [KERNEL DEBUG] [0xffffff80456d2000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2011 (LE Add Device To White List) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:17.947] [HCI EVENT] LE Meta Event - LE Connection Complete - Status: 0x02 - Connection Handle: 0x0040
    [Mar 25 13:33:17.948] [HCI EVENT] [2011] Command Complete - LE Add Device To White List
    [Mar 25 13:33:17.949] [HCI COMMAND] [200D] LE Create Connection - 00:00:00:00:00:00
    [Mar 25 13:33:17.949] [KERNEL DEBUG] [0xffffff80456d2000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200D (LE Create Connection) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:17.953] [HCI EVENT] Command Status - LE Create Connection
    [Mar 25 13:33:17.953] [KERNEL DEBUG] **** [IOBluetoothHCIController][SetIdleTimerValue] -- newValue = 0, mDesiredIdleTimerValue = 0 -- this = 0xffffff8039b56000 ****
    [Mar 25 13:33:17.953] [KERNEL DEBUG] **** [IOBluetoothHCIController][ChangeIdleTime] -- No change -- idle time (0 milliseconds) is the same -- this = 0xffffff8039b56000 ****
    [Mar 25 13:33:18.067] [HCI EVENT] LE Meta Event - LE Connection Complete - Status: 0x00 - Connection Handle: 0x0040
    [Mar 25 13:33:18.069] [KERNEL DEBUG] **** [IOBluetoothHCIController][IncrementActiveConnections] -- calling ChangeIdleTime (38356)
    [Mar 25 13:33:18.073] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 13:33:18.074] [KERNEL DEBUG] [0xffffff8045768000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:18.075] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 13:33:18.076] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 13:33:18.076] [KERNEL DEBUG] [0xffffff8045768000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:18.078] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 13:33:18.080] [HCI COMMAND] [1405] Read RSSI - Connection Handle: 0x0040
    [Mar 25 13:33:18.080] [KERNEL DEBUG] [0xffffff80463e3000] [IOBluetoothHCIRequest][Start] -- OpCode 0x1405 (Read RSSI) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:18.081] [HCI EVENT] [1405] Command Complete - Read RSSI
    [Mar 25 13:33:18.093] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045a3a300, timer = 0xffffff8047a69600
    [Mar 25 13:33:18.093] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045a3a300, timer = 0xffffff8047a69600
    [Mar 25 13:33:18.093] [ATT Send] Exchange MTU Request - MTU:104
    [Mar 25 13:33:18.134] [ATT Receive] Exchange MTU Response - MTU:23
    [Mar 25 13:33:18.134] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047711100, timer = 0xffffff8047e11b00
    [Mar 25 13:33:18.134] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047711100, timer = 0xffffff8047e11b00
    [Mar 25 13:33:18.134] [ATT Send] Read By Type Request - Start Handle:0x0001 - End Handle:0xffff - UUID:2A00 (Device Name)
    [Mar 25 13:33:18.157] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.157] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8045a3a300, aclPacket->ACLTimer = 0xffffff8047a69600 ****
    [Mar 25 13:33:18.157] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047711100, aclPacket->ACLTimer = 0xffffff8047e11b00 ****
    [Mar 25 13:33:18.179] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.183] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047bb2480
    [Mar 25 13:33:18.183] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047bb2480
    [Mar 25 13:33:18.183] [ATT Send] Read By Group Type Request - Start Handle:0x0001 - End Handle:0xffff - UUID:2800 (GATT Primary Service Declaration)
    [Mar 25 13:33:18.224] [ATT Receive] Read By Group Type Response
    [Mar 25 13:33:18.224] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80463bf200, timer = 0xffffff8047748380
    [Mar 25 13:33:18.224] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80463bf200, timer = 0xffffff8047748380
    [Mar 25 13:33:18.224] [ATT Send] Read By Group Type Request - Start Handle:0x0023 - End Handle:0xffff - UUID:2800 (GATT Primary Service Declaration)
    [Mar 25 13:33:18.247] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.247] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047a7f500, aclPacket->ACLTimer = 0xffffff8047bb2480 ****
    [Mar 25 13:33:18.247] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80463bf200, aclPacket->ACLTimer = 0xffffff8047748380 ****
    [Mar 25 13:33:18.269] [ATT Receive] Read By Group Type Response
    [Mar 25 13:33:18.269] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047a69e80
    [Mar 25 13:33:18.269] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047a69e80
    [Mar 25 13:33:18.269] [ATT Send] Read By Group Type Request - Start Handle:0x002e - End Handle:0xffff - UUID:2800 (GATT Primary Service Declaration)
    [Mar 25 13:33:18.314] [ATT Receive] Read By Group Type Response
    [Mar 25 13:33:18.314] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804553d800, timer = 0xffffff8047d84180
    [Mar 25 13:33:18.314] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804553d800, timer = 0xffffff8047d84180
    [Mar 25 13:33:18.314] [ATT Send] Read By Group Type Request - Start Handle:0x003b - End Handle:0xffff - UUID:2800 (GATT Primary Service Declaration)
    [Mar 25 13:33:18.337] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.337] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047a7f500, aclPacket->ACLTimer = 0xffffff8047a69e80 ****
    [Mar 25 13:33:18.337] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804553d800, aclPacket->ACLTimer = 0xffffff8047d84180 ****
    [Mar 25 13:33:18.359] [ATT Receive] Read By Group Type Response
    [Mar 25 13:33:18.359] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80463dc900, timer = 0xffffff8047df4380
    [Mar 25 13:33:18.359] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80463dc900, timer = 0xffffff8047df4380
    [Mar 25 13:33:18.359] [ATT Send] Read By Group Type Request - Start Handle:0x0044 - End Handle:0xffff - UUID:2800 (GATT Primary Service Declaration)
    [Mar 25 13:33:18.404] [ATT Receive] Read By Group Type Response
    [Mar 25 13:33:18.404] [HCI COMMAND] [200E] LE Create Connection Cancel
    [Mar 25 13:33:18.404] [KERNEL DEBUG] [0xffffff8047cda000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200E (LE Create Connection Cancel) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:18.405] [HCI EVENT] [200E] Command Complete - LE Create Connection Cancel - Status: 0x0C
    [Mar 25 13:33:18.406] [HCI COMMAND] [2012] LE Remove Device From White List - AB:03:FF:FF:BB:DD
    [Mar 25 13:33:18.406] [KERNEL DEBUG] [0xffffff8047cda000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2012 (LE Remove Device From White List) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:18.407] [HCI EVENT] [2012] Command Complete - LE Remove Device From White List
    [Mar 25 13:33:18.408] [HCI COMMAND] [200D] LE Create Connection - 00:00:00:00:00:00
    [Mar 25 13:33:18.408] [KERNEL DEBUG] [0xffffff8045532000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200D (LE Create Connection) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:18.411] [HCI EVENT] Command Status - LE Create Connection
    [Mar 25 13:33:18.411] [KERNEL DEBUG] **** [IOBluetoothHCIController][SetIdleTimerValue] -- newValue = 0, mDesiredIdleTimerValue = 0 -- this = 0xffffff8039b56000 ****
    [Mar 25 13:33:18.411] [KERNEL DEBUG] **** [IOBluetoothHCIController][ChangeIdleTime] -- No change -- idle time (0 milliseconds) is the same -- this = 0xffffff8039b56000 ****
    [Mar 25 13:33:18.412] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80451b3300, timer = 0xffffff8047a69e80
    [Mar 25 13:33:18.412] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff80451b3300, timer = 0xffffff8047a69e80
    [Mar 25 13:33:18.412] [ATT Send] Read By Type Request - Start Handle:0x0001 - End Handle:0x000b - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.427] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.427] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80463dc900, aclPacket->ACLTimer = 0xffffff8047df4380 ****
    [Mar 25 13:33:18.427] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80451b3300, aclPacket->ACLTimer = 0xffffff8047a69e80 ****
    [Mar 25 13:33:18.449] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.449] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047aab000, timer = 0xffffff8047bb2c80
    [Mar 25 13:33:18.449] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047aab000, timer = 0xffffff8047bb2c80
    [Mar 25 13:33:18.449] [ATT Send] Read By Type Request - Start Handle:0x0008 - End Handle:0x000b - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.494] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.494] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803d4ea600, timer = 0xffffff8037c76300
    [Mar 25 13:33:18.494] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803d4ea600, timer = 0xffffff8037c76300
    [Mar 25 13:33:18.494] [ATT Send] Read By Type Request - Start Handle:0x000c - End Handle:0x000f - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.517] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.517] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047aab000, aclPacket->ACLTimer = 0xffffff8047bb2c80 ****
    [Mar 25 13:33:18.517] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff803d4ea600, aclPacket->ACLTimer = 0xffffff8037c76300 ****
    [Mar 25 13:33:18.539] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.539] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047e2e900, timer = 0xffffff8047d82080
    [Mar 25 13:33:18.539] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047e2e900, timer = 0xffffff8047d82080
    [Mar 25 13:33:18.539] [ATT Send] Read By Type Request - Start Handle:0x0010 - End Handle:0x0022 - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.584] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.584] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8046421700, timer = 0xffffff8047aeb080
    [Mar 25 13:33:18.584] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8046421700, timer = 0xffffff8047aeb080
    [Mar 25 13:33:18.584] [ATT Send] Read By Type Request - Start Handle:0x0017 - End Handle:0x0022 - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.607] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.607] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047e2e900, aclPacket->ACLTimer = 0xffffff8047d82080 ****
    [Mar 25 13:33:18.607] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8046421700, aclPacket->ACLTimer = 0xffffff8047aeb080 ****
    [Mar 25 13:33:18.629] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.629] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047e2e900, timer = 0xffffff8047bf4580
    [Mar 25 13:33:18.629] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047e2e900, timer = 0xffffff8047bf4580
    [Mar 25 13:33:18.629] [ATT Send] Read By Type Request - Start Handle:0x001d - End Handle:0x0022 - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.674] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.674] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e0a200, timer = 0xffffff80464d9200
    [Mar 25 13:33:18.674] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e0a200, timer = 0xffffff80464d9200
    [Mar 25 13:33:18.674] [ATT Send] Read By Type Request - Start Handle:0x0044 - End Handle:0xffff - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.697] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.697] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047e2e900, aclPacket->ACLTimer = 0xffffff8047bf4580 ****
    [Mar 25 13:33:18.697] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047e0a200, aclPacket->ACLTimer = 0xffffff80464d9200 ****
    [Mar 25 13:33:18.719] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.719] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80476fe000, timer = 0xffffff8047bb0b80
    [Mar 25 13:33:18.719] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80476fe000, timer = 0xffffff8047bb0b80
    [Mar 25 13:33:18.719] [ATT Send] Read By Type Request - Start Handle:0x004c - End Handle:0xffff - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.764] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.764] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a7e700, timer = 0xffffff8047aeb080
    [Mar 25 13:33:18.764] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a7e700, timer = 0xffffff8047aeb080
    [Mar 25 13:33:18.764] [ATT Send] Read By Type Request - Start Handle:0x0055 - End Handle:0xffff - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.787] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.787] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80476fe000, aclPacket->ACLTimer = 0xffffff8047bb0b80 ****
    [Mar 25 13:33:18.787] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047a7e700, aclPacket->ACLTimer = 0xffffff8047aeb080 ****
    [Mar 25 13:33:18.809] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.809] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80476fe000, timer = 0xffffff8037c76300
    [Mar 25 13:33:18.809] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80476fe000, timer = 0xffffff8037c76300
    [Mar 25 13:33:18.809] [ATT Send] Read By Type Request - Start Handle:0x005f - End Handle:0xffff - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.854] [ATT Receive] Read By Type Response
    [Mar 25 13:33:18.854] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803aae1f00, timer = 0xffffff8047aeb000
    [Mar 25 13:33:18.854] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803aae1f00, timer = 0xffffff8047aeb000
    [Mar 25 13:33:18.854] [ATT Send] Read By Type Request - Start Handle:0x0064 - End Handle:0xffff - UUID:2803 (GATT Characteristic Declaration)
    [Mar 25 13:33:18.877] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.877] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff80476fe000, aclPacket->ACLTimer = 0xffffff8037c76300 ****
    [Mar 25 13:33:18.877] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff803aae1f00, aclPacket->ACLTimer = 0xffffff8047aeb000 ****
    [Mar 25 13:33:18.899] [ATT Receive] Error Response - Attribute Handle: 0x0064 - Error Code: 0x0A
    [Mar 25 13:33:18.899] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8044a71f00, timer = 0xffffff8047641880
    [Mar 25 13:33:18.899] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8044a71f00, timer = 0xffffff8047641880
    [Mar 25 13:33:18.899] [ATT Send] Read Request - Handle:0x000b
    [Mar 25 13:33:18.944] [ATT Receive] Read Response
    [Mar 25 13:33:18.944] [HCI COMMAND] [2013] LE Connection Update - Connection Handle: 0x0040
    [Mar 25 13:33:18.944] [KERNEL DEBUG] [0xffffff804656f000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2013 (LE Connection Update) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:18.948] [HCI EVENT] Command Status - LE Connection Update
    [Mar 25 13:33:18.948] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047ad0e00, timer = 0xffffff804651c600
    [Mar 25 13:33:18.948] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047ad0e00, timer = 0xffffff804651c600
    [Mar 25 13:33:18.948] [ATT Send] Find Information Request - Start Handle:0x000f - End Handle:0x000f
    [Mar 25 13:33:18.967] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:18.967] [LMP IN] [SEND]Low Energy LL Control PDU LMP Message - [0x00] Connection Update Req
    [Mar 25 13:33:18.989] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:18.989] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8044a71f00, aclPacket->ACLTimer = 0xffffff8047641880 ****
    [Mar 25 13:33:18.989] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047ad0e00, aclPacket->ACLTimer = 0xffffff804651c600 ****
    [Mar 25 13:33:19.011] [ATT Receive] Find Information Response
    [Mar 25 13:33:19.012] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8044a71f00, timer = 0xffffff8047aeb000
    [Mar 25 13:33:19.012] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8044a71f00, timer = 0xffffff8047aeb000
    [Mar 25 13:33:19.012] [ATT Send] Read Request - Handle:0x0016
    [Mar 25 13:33:19.057] [ATT Receive] Read Response
    [Mar 25 13:33:19.057] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e2f900, timer = 0xffffff8047bb0500
    [Mar 25 13:33:19.057] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e2f900, timer = 0xffffff8047bb0500
    [Mar 25 13:33:19.057] [ATT Send] Read Request - Handle:0x001e
    [Mar 25 13:33:19.079] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:19.079] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8044a71f00, aclPacket->ACLTimer = 0xffffff8047aeb000 ****
    [Mar 25 13:33:19.079] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047e2f900, aclPacket->ACLTimer = 0xffffff8047bb0500 ****
    [Mar 25 13:33:19.238] [ATT Receive] Read Response
    [Mar 25 13:33:19.238] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7d100, timer = 0xffffff80451df180
    [Mar 25 13:33:19.238] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047a7d100, timer = 0xffffff80451df180
    [Mar 25 13:33:19.238] [ATT Send] Read Request - Handle:0x0022
    [Mar 25 13:33:19.239] [HCI EVENT] LE Meta Event - LE Connection Update Complete
    [Mar 25 13:33:19.591] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:19.591] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff8047a7d100, aclPacket->ACLTimer = 0xffffff80451df180 ****
    [Mar 25 13:33:19.598] [ATT Receive] Read Response
    [Mar 25 13:33:19.598] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047b85700, timer = 0xffffff8045d4b200
    [Mar 25 13:33:19.598] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047b85700, timer = 0xffffff8045d4b200
    [Mar 25 13:33:19.598] [ATT Send] Read Request - Handle:0x0047
    [Mar 25 13:33:19.957] [ATT Receive] Error Response - Attribute Handle: 0x0047 - Error Code: 0x0F
    [Mar 25 13:33:19.958] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a39300, timer = 0xffffff8045e9da00
    [Mar 25 13:33:19.958] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a39300, timer = 0xffffff8045e9da00
    [Mar 25 13:33:19.958] [SMP Send] LE SMP Pairing Request Command
    [Mar 25 13:33:19.966] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:19.966] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047b85700, aclPacket->ACLTimer = 0xffffff8045d4b200 ****
    [Mar 25 13:33:20.317] [SMP Receive] LE SMP Pairing Response Command
    [Mar 25 13:33:20.341] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:20.341] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff8047a39300, aclPacket->ACLTimer = 0xffffff8045e9da00 ****
    [Mar 25 13:33:20.490] [HCI COMMAND] [200C] LE Set Scan Enable - 0x00
    [Mar 25 13:33:20.490] [KERNEL DEBUG] [0xffffff8045840000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:20.491] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:20.493] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:20.493] [KERNEL DEBUG] [0xffffff8047cda000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.494] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:20.494] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    [Mar 25 13:33:20.494] [KERNEL DEBUG] [0xffffff8047cda000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.495] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 19
    [Mar 25 13:33:20.495] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    [Mar 25 13:33:20.495] [KERNEL DEBUG] [0xffffff8047cda000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.496] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 18
    [Mar 25 13:33:20.497] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:20.497] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.498] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:20.498] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1089 - LE Resolve Address Whitelist - LE Detect When a Sensor Goes Away - LE Dynamic Scan - LE Allow Scatternet
    [Mar 25 13:33:20.498] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.499] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    [Mar 25 13:33:20.499] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Content Feature Section - Action: Write
    [Mar 25 13:33:20.499] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.500] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Feature Section - Action: Write
    [Mar 25 13:33:20.500] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Service UUID - Action: Clear
    [Mar 25 13:33:20.500] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.501] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Service UUID - Action: Clear, Available Space: 8
    [Mar 25 13:33:20.501] [HCI COMMAND] [FD47] Vendor Specific Command [FD47] - Extended Duplicate Filter
    [Mar 25 13:33:20.501] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.502] [HCI EVENT] [FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    [Mar 25 13:33:20.502] [HCI COMMAND] [200B] LE Set Scan Parameters
    [Mar 25 13:33:20.502] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200B (LE Set Scan Parameters) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:20.503] [HCI EVENT] [200B] Command Complete - LE Set Scan Parameters
    [Mar 25 13:33:20.503] [HCI COMMAND] [200C] LE Set Scan Enable - 0x01
    [Mar 25 13:33:20.503] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:20.504] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:22.118] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:22.118] [LMP IN] [SEND]Low Energy LL Control PDU LMP Message - [0x01] Channel Map Req
    [Mar 25 13:33:28.686] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045e97200, timer = 0xffffff8047bc2f00
    [Mar 25 13:33:28.686] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045e97200, timer = 0xffffff8047bc2f00
    [Mar 25 13:33:28.686] [SMP Send] LE SMP Pairing Confirm Command
    [Mar 25 13:33:28.957] [SMP Receive] LE SMP Pairing Confirm Command
    [Mar 25 13:33:28.966] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:28.966] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff8045e97200, aclPacket->ACLTimer = 0xffffff8047bc2f00 ****
    [Mar 25 13:33:28.967] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045544f00, timer = 0xffffff8047bc2d00
    [Mar 25 13:33:28.967] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045544f00, timer = 0xffffff8047bc2d00
    [Mar 25 13:33:28.967] [SMP Send] LE SMP Pairing Random Command
    [Mar 25 13:33:29.318] [SMP Receive] LE SMP Pairing Random Command
    [Mar 25 13:33:29.341] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:29.341] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff8045544f00, aclPacket->ACLTimer = 0xffffff8047bc2d00 ****
    [Mar 25 13:33:29.341] [HCI COMMAND] [2019] LE Start Encryption - Connection Handle: 0x0040
    [Mar 25 13:33:29.341] [KERNEL DEBUG] [0xffffff8046409000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2019 (LE Start Encryption) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:29.342] [HCI EVENT] Command Status - LE Start Encryption
    [Mar 25 13:33:29.498] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:29.498] [LMP IN] [SEND]Low Energy LL Control PDU LMP Message - [0x03] Enc Req
    [Mar 25 13:33:29.678] [LMP IN] [REC] Low Energy LL Control PDU LMP Message - [0x04] Enc Rsp
    [Mar 25 13:33:29.678] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:30.038] [LMP IN] [REC] Low Energy LL Control PDU LMP Message - [0x05] Start Enc Req
    [Mar 25 13:33:30.038] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:30.218] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:30.218] [LMP IN] [SEND]Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
    [Mar 25 13:33:30.398] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:30.398] [LMP IN] [REC] Low Energy LL Control PDU LMP Message - [0x06] Start Enc Rsp
    [Mar 25 13:33:30.398] [HCI EVENT] Encryption Change Complete - Encryption Enabled - Connection Handle: 0x0040
    [Mar 25 13:33:30.578] [SMP Receive] LE SMP Encryption Information Command
    [Mar 25 13:33:30.578] [SMP Receive] LE SMP Master Identification Command
    [Mar 25 13:33:30.579] [SMP Receive] LE SMP Identity Information Command
    [Mar 25 13:33:30.579] [SMP Receive] LE SMP Identity Address Information Command
    [Mar 25 13:33:30.580] [SMP Receive] LE SMP Code Signing Information Command
    [Mar 25 13:33:30.595] [HCI COMMAND] [0C52] Write Extended Inquiry Response
    [Mar 25 13:33:30.595] [KERNEL DEBUG] [0xffffff804579b000] [IOBluetoothHCIRequest][Start] -- OpCode 0x0C52 (Write Extended Inquiry Response) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:30.596] [HCI EVENT] [0C52] Command Complete - Write Extended Inquiry Response
    [Mar 25 13:33:30.599] [HCI COMMAND] [0C1A] Write Scan Enable - Requesting Scan State: 0x03
    [Mar 25 13:33:30.599] [KERNEL DEBUG] [0xffffff804579b000] [IOBluetoothHCIRequest][Start] -- OpCode 0x0C1A (Write Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:30.600] [HCI EVENT] [0C1A] Command Complete - Write Scan Enable
    [Mar 25 13:33:30.602] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80478c2000, timer = 0xffffff8047d90c80
    [Mar 25 13:33:30.602] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff80478c2000, timer = 0xffffff8047d90c80
    [Mar 25 13:33:30.602] [SMP Send] LE SMP Encryption Information Command
    [Mar 25 13:33:30.967] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:30.967] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff80478c2000, aclPacket->ACLTimer = 0xffffff8047d90c80 ****
    [Mar 25 13:33:30.967] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047c35100, timer = 0xffffff8047bb0480
    [Mar 25 13:33:30.967] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8047c35100, timer = 0xffffff8047bb0480
    [Mar 25 13:33:30.967] [SMP Send] LE SMP Master Identification Command
    [Mar 25 13:33:31.297] [L2CAP RECEIVE] Connection Parameter Update Request
    [Mar 25 13:33:31.298] [ATT Receive] Handle Value Notification - Handle:0x0054 - Value:00
    [Mar 25 13:33:31.299] [ATT Receive] Handle Value Notification - Handle:0x0050 - Value:00 00 00 00 00 00 00 00
    [Mar 25 13:33:31.341] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:31.341] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 1, aclPacket = 0xffffff8047c35100, aclPacket->ACLTimer = 0xffffff8047bb0480 ****
    [Mar 25 13:33:31.342] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff803d262e00, timer = 0xffffff8047aebc00
    [Mar 25 13:33:31.342] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff803d262e00, timer = 0xffffff8047aebc00
    [Mar 25 13:33:31.342] [ATT Send] Read Request - Handle:0x0047
    [Mar 25 13:33:31.343] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047aa7700, timer = 0xffffff80464d2900
    [Mar 25 13:33:31.343] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047aa7700, timer = 0xffffff80464d2900
    [Mar 25 13:33:31.343] [L2CAP SEND] Connection Parameter Update Response
    [Mar 25 13:33:31.343] [HCI COMMAND] [2013] LE Connection Update - Connection Handle: 0x0040
    [Mar 25 13:33:31.343] [KERNEL DEBUG] [0xffffff804579b000] [IOBluetoothHCIRequest][Start] -- OpCode 0x2013 (LE Connection Update) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:31.347] [HCI EVENT] Command Status - LE Connection Update
    [Mar 25 13:33:31.478] [KERNEL DEBUG] **** [BroadcomBluetoothHostControllerUSBTransport][LMPLoggingBulkInReadHandler] -- mInt2BulkInPipe->Read() success
    [Mar 25 13:33:31.478] [LMP IN] [SEND]Low Energy LL Control PDU LMP Message - [0x00] Connection Update Req
    [Mar 25 13:33:31.659] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:31.659] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff803d262e00, aclPacket->ACLTimer = 0xffffff8047aebc00 ****
    [Mar 25 13:33:31.659] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047aa7700, aclPacket->ACLTimer = 0xffffff80464d2900 ****
    [Mar 25 13:33:31.838] [ATT Receive] Read Response
    [Mar 25 13:33:31.838] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045cf8b00, timer = 0xffffff8047c01300
    [Mar 25 13:33:31.838] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff8045cf8b00, timer = 0xffffff8047c01300
    [Mar 25 13:33:31.838] [ATT Send] Read Request - Handle:0x004d
    [Mar 25 13:33:32.198] [ATT Receive] Read Response
    [Mar 25 13:33:32.198] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e14f00, timer = 0xffffff804777ac00
    [Mar 25 13:33:32.198] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047e14f00, timer = 0xffffff804777ac00
    [Mar 25 13:33:32.198] [ATT Send] Read Blob Request
    [Mar 25 13:33:32.246] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0001
    [Mar 25 13:33:32.246] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8045cf8b00, aclPacket->ACLTimer = 0xffffff8047c01300 ****
    [Mar 25 13:33:32.563] [ATT Receive] Read Blob Response
    [Mar 25 13:33:32.563] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804557c400, timer = 0xffffff8047853a00
    [Mar 25 13:33:32.563] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804557c400, timer = 0xffffff8047853a00
    [Mar 25 13:33:32.563] [ATT Send] Read Blob Request
    [Mar 25 13:33:32.583] [ATT Receive] Read Blob Response
    [Mar 25 13:33:32.583] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff803d4edc00, timer = 0xffffff8047b0be00
    [Mar 25 13:33:32.583] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff803d4edc00, timer = 0xffffff8047b0be00
    [Mar 25 13:33:32.583] [ATT Send] Read Blob Request
    [Mar 25 13:33:32.594] [HCI EVENT] LE Meta Event - LE Connection Update Complete
    [Mar 25 13:33:32.595] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0003
    [Mar 25 13:33:32.595] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff8047e14f00, aclPacket->ACLTimer = 0xffffff804777ac00 ****
    [Mar 25 13:33:32.595] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff804557c400, aclPacket->ACLTimer = 0xffffff8047853a00 ****
    [Mar 25 13:33:32.595] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff803d4edc00, aclPacket->ACLTimer = 0xffffff8047b0be00 ****
    [Mar 25 13:33:32.603] [ATT Receive] Read Blob Response
    [Mar 25 13:33:32.603] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804557c400, timer = 0xffffff8047bb2880
    [Mar 25 13:33:32.603] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804557c400, timer = 0xffffff8047bb2880
    [Mar 25 13:33:32.603] [ATT Send] Read Blob Request
    [Mar 25 13:33:32.623] [ATT Receive] Read Blob Response
    [Mar 25 13:33:32.623] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047d84300
    [Mar 25 13:33:32.623] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047a7f500, timer = 0xffffff8047d84300
    [Mar 25 13:33:32.623] [ATT Send] Read Blob Request
    [Mar 25 13:33:32.643] [ATT Receive] Read Blob Response
    [Mar 25 13:33:32.643] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff8045e01e00, timer = 0xffffff803e788f80
    [Mar 25 13:33:32.643] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff8045e01e00, timer = 0xffffff803e788f80
    [Mar 25 13:33:32.643] [ATT Send] Find Information Request - Start Handle:0x0051 - End Handle:0x0052
    [Mar 25 13:33:32.659] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0003
    [Mar 25 13:33:32.659] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff804557c400, aclPacket->ACLTimer = 0xffffff8047bb2880 ****
    [Mar 25 13:33:32.659] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff8047a7f500, aclPacket->ACLTimer = 0xffffff8047d84300 ****
    [Mar 25 13:33:32.659] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff8045e01e00, aclPacket->ACLTimer = 0xffffff803e788f80 ****
    [Mar 25 13:33:32.673] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.673] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff803d6ad380
    [Mar 25 13:33:32.673] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff803d6ad380
    [Mar 25 13:33:32.673] [ATT Send] Find Information Request - Start Handle:0x0051 - End Handle:0x0052
    [Mar 25 13:33:32.703] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.703] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8045dbd400, timer = 0xffffff8047b0be00
    [Mar 25 13:33:32.703] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8045dbd400, timer = 0xffffff8047b0be00
    [Mar 25 13:33:32.703] [ATT Send] Find Information Request - Start Handle:0x0055 - End Handle:0x0056
    [Mar 25 13:33:32.714] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:32.714] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804555b100, aclPacket->ACLTimer = 0xffffff803d6ad380 ****
    [Mar 25 13:33:32.714] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8045dbd400, aclPacket->ACLTimer = 0xffffff8047b0be00 ****
    [Mar 25 13:33:32.733] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.733] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff80478ba800
    [Mar 25 13:33:32.733] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff80478ba800
    [Mar 25 13:33:32.733] [ATT Send] Find Information Request - Start Handle:0x0055 - End Handle:0x0056
    [Mar 25 13:33:32.763] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.763] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047c3b500, timer = 0xffffff8047bb0480
    [Mar 25 13:33:32.763] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047c3b500, timer = 0xffffff8047bb0480
    [Mar 25 13:33:32.763] [ATT Send] Find Information Request - Start Handle:0x0059 - End Handle:0x0059
    [Mar 25 13:33:32.777] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:32.777] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804555b100, aclPacket->ACLTimer = 0xffffff80478ba800 ****
    [Mar 25 13:33:32.777] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047c3b500, aclPacket->ACLTimer = 0xffffff8047bb0480 ****
    [Mar 25 13:33:32.782] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.783] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff804777d700
    [Mar 25 13:33:32.783] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804555b100, timer = 0xffffff804777d700
    [Mar 25 13:33:32.783] [ATT Send] Find Information Request - Start Handle:0x0064 - End Handle:0xffff
    [Mar 25 13:33:32.803] [ATT Receive] Find Information Response
    [Mar 25 13:33:32.803] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8045a3d000, timer = 0xffffff8044f97600
    [Mar 25 13:33:32.803] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8045a3d000, timer = 0xffffff8044f97600
    [Mar 25 13:33:32.803] [ATT Send] Find Information Request - Start Handle:0x0065 - End Handle:0xffff
    [Mar 25 13:33:32.814] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:32.814] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804555b100, aclPacket->ACLTimer = 0xffffff804777d700 ****
    [Mar 25 13:33:32.814] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8045a3d000, aclPacket->ACLTimer = 0xffffff8044f97600 ****
    [Mar 25 13:33:32.822] [ATT Receive] Error Response - Attribute Handle: 0x0065 - Error Code: 0x0A
    [Mar 25 13:33:32.823] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804658c800, timer = 0xffffff80478ba800
    [Mar 25 13:33:32.823] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804658c800, timer = 0xffffff80478ba800
    [Mar 25 13:33:32.823] [ATT Send] Write Request - Handle:0x000f
    [Mar 25 13:33:32.842] [ATT Receive] Write Response
    [Mar 25 13:33:32.843] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047bb0480
    [Mar 25 13:33:32.843] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047bb0480
    [Mar 25 13:33:32.843] [ATT Send] Read Request - Handle:0x0052
    [Mar 25 13:33:32.862] [ATT Receive] Read Response
    [Mar 25 13:33:32.863] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff8047e2b600, timer = 0xffffff8047bf4580
    [Mar 25 13:33:32.863] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 2, aclMemDescriptor = 0xffffff8047e2b600, timer = 0xffffff8047bf4580
    [Mar 25 13:33:32.863] [ATT Send] Write Request - Handle:0x0051
    [Mar 25 13:33:32.876] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0003
    [Mar 25 13:33:32.876] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff804658c800, aclPacket->ACLTimer = 0xffffff80478ba800 ****
    [Mar 25 13:33:32.876] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff804624ac00, aclPacket->ACLTimer = 0xffffff8047bb0480 ****
    [Mar 25 13:33:32.876] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 3, aclPacket = 0xffffff8047e2b600, aclPacket->ACLTimer = 0xffffff8047bf4580 ****
    [Mar 25 13:33:32.882] [ATT Receive] Write Response
    [Mar 25 13:33:32.883] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047bb4000
    [Mar 25 13:33:32.883] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047bb4000
    [Mar 25 13:33:32.883] [ATT Send] Read Request - Handle:0x0056
    [Mar 25 13:33:32.902] [ATT Receive] Read Response
    [Mar 25 13:33:32.903] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047ac3f00, timer = 0xffffff8047b99e00
    [Mar 25 13:33:32.903] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff8047ac3f00, timer = 0xffffff8047b99e00
    [Mar 25 13:33:32.903] [ATT Send] Write Request - Handle:0x0055
    [Mar 25 13:33:32.913] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:32.913] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804624ac00, aclPacket->ACLTimer = 0xffffff8047bb4000 ****
    [Mar 25 13:33:32.913] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff8047ac3f00, aclPacket->ACLTimer = 0xffffff8047b99e00 ****
    [Mar 25 13:33:32.923] [ATT Receive] Write Response
    [Mar 25 13:33:32.923] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047b8b400
    [Mar 25 13:33:32.923] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 0, aclMemDescriptor = 0xffffff804624ac00, timer = 0xffffff8047b8b400
    [Mar 25 13:33:32.923] [ATT Send] Read Request - Handle:0x0059
    [Mar 25 13:33:32.943] [ATT Receive] Read Response
    [Mar 25 13:33:32.943] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called mWorkLoop->addEventSource(aclMemDescriptor->ACLTimer) -- result = kIOReturnSuccess -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803df8cd00, timer = 0xffffff803e0dd800
    [Mar 25 13:33:32.943] [KERNEL DEBUG] **** [IOBluetoothHCIController][SendACLData] -- called aclMemDescriptor->ACLTimer->setTimeoutMS() -- result = kIOReturnSuccess -- Start ACL Packet timer -- mNumOutstandingACLPackets = 1, aclMemDescriptor = 0xffffff803df8cd00, timer = 0xffffff803e0dd800
    [Mar 25 13:33:32.943] [ATT Send] Read Request - Handle:0x0064
    [Mar 25 13:33:32.954] [HCI EVENT] Number of Completed Packets - Connection Handle: 0x0040 - Packets: 0x0002
    [Mar 25 13:33:32.954] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff804624ac00, aclPacket->ACLTimer = 0xffffff8047b8b400 ****
    [Mar 25 13:33:32.954] [NOTE] **** [IOBluetoothDevice][decrementNumberOfOutstandingPacketsBy] -- calling removeEventSource() -- hostController = 0xffffff8039b56000, mNumOutstandingACLPackets = 2, aclPacket = 0xffffff803df8cd00, aclPacket->ACLTimer = 0xffffff803e0dd800 ****
    [Mar 25 13:33:32.963] [ATT Receive] Read Response
    [Mar 25 13:33:34.604] [HCI COMMAND] [200C] LE Set Scan Enable - 0x00
    [Mar 25 13:33:34.604] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:34.625] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:34.627] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:34.627] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.628] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:34.629] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    [Mar 25 13:33:34.629] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.629] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 19
    [Mar 25 13:33:34.630] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    [Mar 25 13:33:34.630] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.630] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 18
    [Mar 25 13:33:34.631] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:34.631] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.632] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:34.633] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1089 - LE Resolve Address Whitelist - LE Detect When a Sensor Goes Away - LE Dynamic Scan - LE Allow Scatternet
    [Mar 25 13:33:34.633] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.633] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    [Mar 25 13:33:34.634] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Content Feature Section - Action: Write
    [Mar 25 13:33:34.634] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.634] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Feature Section - Action: Write
    [Mar 25 13:33:34.635] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Service UUID - Action: Clear
    [Mar 25 13:33:34.635] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.636] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Service UUID - Action: Clear, Available Space: 8
    [Mar 25 13:33:34.636] [HCI COMMAND] [FD47] Vendor Specific Command [FD47] - Extended Duplicate Filter
    [Mar 25 13:33:34.636] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.636] [HCI EVENT] [FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    [Mar 25 13:33:34.637] [HCI COMMAND] [200B] LE Set Scan Parameters
    [Mar 25 13:33:34.637] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200B (LE Set Scan Parameters) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:34.637] [HCI EVENT] [200B] Command Complete - LE Set Scan Parameters
    [Mar 25 13:33:34.638] [HCI COMMAND] [200C] LE Set Scan Enable - 0x01
    [Mar 25 13:33:34.638] [KERNEL DEBUG] [0xffffff80463e6000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:34.638] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:34.641] [HCI COMMAND] [0401] HCI Inquiry
    [Mar 25 13:33:34.641] [KERNEL DEBUG] [0xffffff803aae0000] [IOBluetoothHCIRequest][Start] -- OpCode 0x0401 (HCI Inquiry) from: blued (57) Asynchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 43210
    [Mar 25 13:33:34.670] [HCI EVENT] Command Status - HCI Inquiry
    [Mar 25 13:33:34.897] [HCI EVENT] Inquiry Result - EIR - 10:48:B1:42:E0:E5 - Phone : Phone - RSSI: -77 dBm
    [Mar 25 13:33:40.713] [ATT Receive] Handle Value Notification - Handle:0x0050 - Value:00 00 0E 00 00 00 00 00
    [Mar 25 13:33:40.751] [HCI COMMAND] [0402] HCI Inquiry Cancel
    [Mar 25 13:33:40.751] [KERNEL DEBUG] [0xffffff8047af5000] [IOBluetoothHCIRequest][Start] -- OpCode 0x0402 (HCI Inquiry Cancel) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.762] [HCI EVENT] [0402] Command Complete - HCI Inquiry Cancel
    [Mar 25 13:33:40.763] [HCI COMMAND] [200C] LE Set Scan Enable - 0x00
    [Mar 25 13:33:40.763] [KERNEL DEBUG] [0xffffff8047af5000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.783] [ATT Receive] Handle Value Notification - Handle:0x0050 - Value:00 00 00 00 00 00 00 00
    [Mar 25 13:33:40.807] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:40.809] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.809] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.810] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.810] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    [Mar 25 13:33:40.810] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.811] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 19
    [Mar 25 13:33:40.811] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    [Mar 25 13:33:40.811] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.812] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 18
    [Mar 25 13:33:40.812] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.812] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.813] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.813] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1089 - LE Resolve Address Whitelist - LE Detect When a Sensor Goes Away - LE Dynamic Scan - LE Allow Scatternet
    [Mar 25 13:33:40.813] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.814] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    [Mar 25 13:33:40.814] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Content Feature Section - Action: Write
    [Mar 25 13:33:40.814] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.815] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Feature Section - Action: Write
    [Mar 25 13:33:40.815] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Service UUID - Action: Clear
    [Mar 25 13:33:40.815] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.816] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Service UUID - Action: Clear, Available Space: 8
    [Mar 25 13:33:40.816] [HCI COMMAND] [FD47] Vendor Specific Command [FD47] - Extended Duplicate Filter
    [Mar 25 13:33:40.816] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.817] [HCI EVENT] [FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    [Mar 25 13:33:40.817] [HCI COMMAND] [200B] LE Set Scan Parameters
    [Mar 25 13:33:40.817] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200B (LE Set Scan Parameters) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.818] [HCI EVENT] [200B] Command Complete - LE Set Scan Parameters
    [Mar 25 13:33:40.818] [HCI COMMAND] [200C] LE Set Scan Enable - 0x01
    [Mar 25 13:33:40.818] [KERNEL DEBUG] [0xffffff8045968000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.819] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:40.820] [HCI COMMAND] [200C] LE Set Scan Enable - 0x00
    [Mar 25 13:33:40.820] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.851] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:40.852] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.852] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.854] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.854] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    [Mar 25 13:33:40.854] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.855] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 19
    [Mar 25 13:33:40.855] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    [Mar 25 13:33:40.855] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.856] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 18
    [Mar 25 13:33:40.856] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.856] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.858] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.858] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1089 - LE Resolve Address Whitelist - LE Detect When a Sensor Goes Away - LE Dynamic Scan - LE Allow Scatternet
    [Mar 25 13:33:40.858] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.859] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    [Mar 25 13:33:40.859] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Content Feature Section - Action: Write
    [Mar 25 13:33:40.859] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.860] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Feature Section - Action: Write
    [Mar 25 13:33:40.860] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Service UUID - Action: Clear
    [Mar 25 13:33:40.860] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.861] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Service UUID - Action: Clear, Available Space: 8
    [Mar 25 13:33:40.861] [HCI COMMAND] [FD47] Vendor Specific Command [FD47] - Extended Duplicate Filter
    [Mar 25 13:33:40.861] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.862] [HCI EVENT] [FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    [Mar 25 13:33:40.862] [HCI COMMAND] [200B] LE Set Scan Parameters
    [Mar 25 13:33:40.862] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200B (LE Set Scan Parameters) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.863] [HCI EVENT] [200B] Command Complete - LE Set Scan Parameters
    [Mar 25 13:33:40.863] [HCI COMMAND] [200C] LE Set Scan Enable - 0x01
    [Mar 25 13:33:40.863] [KERNEL DEBUG] [0xffffff8047afd000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.864] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:40.870] [HCI COMMAND] [0C1A] Write Scan Enable - Requesting Scan State: 0x02
    [Mar 25 13:33:40.870] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0x0C1A (Write Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.895] [HCI EVENT] [0C1A] Command Complete - Write Scan Enable
    [Mar 25 13:33:40.895] [HCI COMMAND] [200C] LE Set Scan Enable - 0x00
    [Mar 25 13:33:40.895] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.900] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:40.901] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.901] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.903] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.903] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 5
    [Mar 25 13:33:40.903] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.904] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 19
    [Mar 25 13:33:40.904] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Add Matching Rule with Address for Type: 9
    [Mar 25 13:33:40.904] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.905] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Add Matching Rule with Address - Capacity: 20, Remaining: 18
    [Mar 25 13:33:40.905] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - Clear Matching Rules
    [Mar 25 13:33:40.905] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.907] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - Clear Matching Rule - Capacity: 20, Remaining: 20
    [Mar 25 13:33:40.907] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Enable Customer Specific Feature: 0x1089 - LE Resolve Address Whitelist - LE Detect When a Sensor Goes Away - LE Dynamic Scan - LE Allow Scatternet
    [Mar 25 13:33:40.907] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.908] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Enable Customer Specific Feature
    [Mar 25 13:33:40.908] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Content Feature Section - Action: Write
    [Mar 25 13:33:40.908] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.909] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Feature Section - Action: Write
    [Mar 25 13:33:40.909] [HCI COMMAND] [FCE9] Vendor Specific Command [FCE9] - LE Meta VSC: LE Adv Packet Filter Service UUID - Action: Clear
    [Mar 25 13:33:40.909] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFCE9 (Broadcom VSC -- LE Meta VSC) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.910] [HCI EVENT] [FCE9] Command Complete - Broadcom VSC Event - LE Meta VSC: LE Adv Packet Content Filter Service UUID - Action: Clear, Available Space: 8
    [Mar 25 13:33:40.910] [HCI COMMAND] [FD47] Vendor Specific Command [FD47] - Extended Duplicate Filter
    [Mar 25 13:33:40.910] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0xFD47 (Broadcom VSC -- LE Extended Duplicate Filter) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.911] [HCI EVENT] [FD47] Command Complete - Broadcom VSC Event - Extended Duplicate Filter
    [Mar 25 13:33:40.911] [HCI COMMAND] [200B] LE Set Scan Parameters
    [Mar 25 13:33:40.911] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200B (LE Set Scan Parameters) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 6543
    [Mar 25 13:33:40.912] [HCI EVENT] [200B] Command Complete - LE Set Scan Parameters
    [Mar 25 13:33:40.912] [HCI COMMAND] [200C] LE Set Scan Enable - 0x01
    [Mar 25 13:33:40.912] [KERNEL DEBUG] [0xffffff8047704000] [IOBluetoothHCIRequest][Start] -- OpCode 0x200C (LE Set Scan Enable) from: blued (57) Synchronous status: 0x00 (kIOReturnSuccess) state: 2 (BUSY) timeout: 4321
    [Mar 25 13:33:40.913] [HCI EVENT] [200C] Command Complete - LE Set Scan Enable
    [Mar 25 13:33:42.032] [ATT Receive] Handle Value Notification - Handle:0x0050 - Value:00 00 0E 00 00 00 00 00
  • Hi Sam,

    It doesn't look like your BLE sniffer traces were attached. All I see is some iOS kernel debug logs (?) that were pasted in. Do you have a protocol sniffer trace showing the working & non-working case? You can use a CC2540USB dongle + the TI BLE Packet Sniffer software to take a trace.

    Also, are you bonding to the iOS device?

    Best wishes
  • Hello JXS,

    The SmartRF packet sniffer does not recognize my capturing device (CC2540 USB Dongle).
    The packet sniffer (set to Bluetooth Low Energy because I want to snoop a CC2541 BLE chip) lists the USB dongle as a possible capturing device. I can use the dongle on BTool and have installed the correct drivers. However, once I start the packet sniffer, nothing appears in the "select capturing device" field.

    The user manual for the packet sniffer says
    "Depending on the selected protocol, the applicable devices will be shown in the list. The list will automatically be updated when an applicable device is connected to a USB connector. "

    but as far as I know an applicable device is connected by USB (or BTool would not work).

    Please advise,
    Sam
  • Hi Sam,

    You must program the CC2540 USB dongle with the packet sniffer FW. This is included in the Packet Sniffer installation.

    Best regards,
    Fredrik