Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Some Android Devices can't complete Bluetooth connection, getting disconnected after 5 seconds due to 0x08 timeout #9

Open
hando-gomes opened this issue Jan 27, 2022 · 64 comments

Comments

@hando-gomes
Copy link

A few Android devices are unable to successfully establish a bluetooth connection to the Raspberry Pi 4.
The devices connect, and after 5 seconds, it is disconnected with a timeout reason code 0x08.

> HCI Event: Disconnect Complete (0x05) plen 4
        Status: Success (0x00)
        Handle: 64
        Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8
        LE Address: 54:FA:15:75:50:1B (Resolvable)
        Reason: Connection timeout (0x01)
@ MGMT Event: Device Disconnected (0x000c) plen 8
        LE Address: 54:FA:15:75:50:1B (Resolvable)
        Reason: Connection timeout (0x01)

The following Android devices have shown this behavior:

  • Pixel Pro 6 - Android 12
  • Samsung Galaxy S10 - Android 10
  • Samsung Galaxy S21 Ultra

I have 2 raspberry pi's running 2 different distros without any modifications and a sample app from the BlueZ study guide for Linux:

  1. Linux raspberrypi 4.19.97-v7l+ #1294 SMP Thu Jan 30 13:21:14 GMT 2020 armv7l
  2. Linux raspberrypi 4.19.118-v7l+ #1311 SMP Mon Apr 27 14:26:42 BST 2020 armv7l GNU/Linux

On 4.19.97 the connection works without a problem.
On 4.19.118, the device connects and after 5 seconds it gets disconnected.

I am looking at these 2 versions as an attempt to narrow the issue to where it seems to start happening.

The main differences between 4.19.97 and and 4.19.118 in regards to Bluetooth
4.19.17:

ii  bluez:armhf                        5.50-1+rpt1                         armhf        Bluetooth tools and daemons
ii  bluez-firmware                     1.2-4+rpt2                          all          Firmware for Bluetooth devices
ii  raspberrypi-bootloader:armhf       1.20200212-1                        armhf        Raspberry Pi bootloader
ii  raspberrypi-kernel:armhf           1.20200212-1                        armhf        Raspberry Pi bootloader

4.19.118:

ii  bluez:armhf                        5.50-1.2~deb10u1+rpt1               armhf        Bluetooth tools and daemons
ii  bluez-firmware                     1.2-4+rpt3                          all          Firmware for Bluetooth devices
ii  raspberrypi-bootloader:armhf       1.20200512-2                        armhf        Raspberry Pi bootloader
ii  raspberrypi-kernel:armhf           1.20200512-2                        armhf        Raspberry Pi bootloader

Steps to reproduce the behaviour

  • Start with fresh image from RPI repository:
  • Download the BlueZ study guide for Linux and transfer the code to the pi
  • Install python-dbus needed for the sample code sudo apt-get install python-dbus
  • stop the bluetooth service sudo systemctl stop bluetooth
  • Verify the path for the bluetoothd ExecPath: $ grep Exec /lib/systemd/system/bluetooth.service
  • Run bluetooth service manually in debug and detached mode: sudo /usr/lib/bluetooth/bluetoothd -d -n
  • Run the sample code sudo python server_advertisement.py
  • Launch nRF Connect app on one of these Android devices to see the failure:
    • Pixel Pro 6 - Android 12, Samsung Galaxy S10 - Android 10, Samsung Galaxy S21 Ultra
  • Scan and connect to raspi

The client will connect, attempt to discover services and after 5 seconds of inactivity the device will be disconnected.

4.19.17 Logs from Android Pixel 6 Pro - Successful connection 👍

btmon:

> HCI Event: LE Meta Event (0x3e) plen 19                                                                                                                                                                                        #437 [hci0] 2022-01-27 18:48:34.627362
      LE Connection Complete (0x01)
        Status: Success (0x00)
        Handle: 66
        Role: Slave (0x01)
        Peer address type: Random (0x01)
        Peer address: 44:AF:A0:2E:DB:CF (Resolvable)
        Connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Master clock accuracy: 0x01
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                                                              {0x0002} [hci0] 2022-01-27 18:48:34.627420
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                                                              {0x0001} [hci0] 2022-01-27 18:48:34.627420
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Flags: 0x00000000
        Data length: 0
> HCI Event: Vendor (0xff) plen 5                                                                                                                                                                                                #438 [hci0] 2022-01-27 18:48:34.627374
        55 00 00 42 00                                   U..B.
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2                                                                                                                                                                 #439 [hci0] 2022-01-27 18:48:34.627651
        Handle: 66
> HCI Event: Command Status (0x0f) plen 4                                                                                                                                                                                        #440 [hci0] 2022-01-27 18:48:34.628413
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: Command Complete (0x0e) plen 14                                                                                                                                                                                     #441 [hci0] 2022-01-27 18:48:34.629155
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
        00 00 00 00 00 00 00 00 00 00                    ..........
> HCI Event: LE Meta Event (0x3e) plen 12                                                                                                                                                                                        #442 [hci0] 2022-01-27 18:48:34.720795
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 66
        Features: 0x2f 0x00 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Connection Parameter Request Procedure
          Extended Reject Indication
          Slave-initiated Features Exchange
          LE Data Packet Length Extension
= bluetoothd: No cache for 44:AF:A0:2E:DB:CF                                                                                                                                                                                                 2022-01-27 18:48:34.727251
< ACL Data TX: Handle 66 flags 0x00 dlen 7                                                                                                                                                                                       #443 [hci0] 2022-01-27 18:48:34.727331
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #444 [hci0] 2022-01-27 18:48:34.810869
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0xffff
        Attribute type: Unknown (0x2b3a)
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #445 [hci0] 2022-01-27 18:48:34.811086
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                                                        #446 [hci0] 2022-01-27 18:48:34.945918
      LE Remote Connection Parameter Request (0x06)
        Handle: 66
        Min connection interval: 7.50 msec (0x0006)
        Max connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
< HCI Command: LE Remote Connection Parameter Request Reply (0x08|0x0020) plen 14                                                                                                                                                #447 [hci0] 2022-01-27 18:48:34.945952
        Handle: 66
        Min connection interval: 7.50 msec (0x0006)
        Max connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Min connection length: 0.000 msec (0x0000)
        Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Complete (0x0e) plen 6                                                                                                                                                                                      #448 [hci0] 2022-01-27 18:48:34.948385
      LE Remote Connection Parameter Request Reply (0x08|0x0020) ncmd 1
        Status: Success (0x00)
        Handle: 66
> ACL Data RX: Handle 66 flags 0x02 dlen 7                                                                                                                                                                                       #449 [hci0] 2022-01-27 18:48:35.215447
      ATT: Exchange MTU Response (0x03) len 2
        Server RX MTU: 517
< ACL Data TX: Handle 66 flags 0x00 dlen 11                                                                                                                                                                                      #450 [hci0] 2022-01-27 18:48:35.216188
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #451 [hci0] 2022-01-27 18:48:35.215864
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #452 [hci0] 2022-01-27 18:48:35.215919
        Num handles: 1
        Handle: 66
        Count: 2
< ACL Data TX: Handle 66 flags 0x00 dlen 18                                                                                                                                                                                      #453 [hci0] 2022-01-27 18:48:35.216791
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
> ACL Data RX: Handle 66 flags 0x02 dlen 18                                                                                                                                                                                      #454 [hci0] 2022-01-27 18:48:35.305666
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0009
        UUID: Generic Attribute Profile (0x1801)
        Handle range: 0x0014-0xffff
        UUID: Generic Access Profile (0x1800)
< ACL Data TX: Handle 66 flags 0x00 dlen 11                                                                                                                                                                                      #455 [hci0] 2022-01-27 18:48:35.306221
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Secondary Service (0x2801)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #456 [hci0] 2022-01-27 18:48:35.306107
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #457 [hci0] 2022-01-27 18:48:35.306126
        Num handles: 1
        Handle: 66
        Count: 2
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #458 [hci0] 2022-01-27 18:48:35.307273
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x000a
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 9                                                                                                                                                                                       #459 [hci0] 2022-01-27 18:48:35.395479
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0001
        Error: Unsupported Group Type (0x10)
< ACL Data TX: Handle 66 flags 0x00 dlen 11                                                                                                                                                                                      #460 [hci0] 2022-01-27 18:48:35.395849
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0xffff
        Attribute type: Include (0x2802)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #461 [hci0] 2022-01-27 18:48:35.395935
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0005
        Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #462 [hci0] 2022-01-27 18:48:35.396056
        Num handles: 1
        Handle: 66
        Count: 2
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #463 [hci0] 2022-01-27 18:48:35.396391
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
> HCI Event: LE Meta Event (0x3e) plen 10                                                                                                                                                                                        #464 [hci0] 2022-01-27 18:48:35.448974
      LE Connection Update Complete (0x03)
        Status: Success (0x00)
        Handle: 66
        Connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #465 [hci0] 2022-01-27 18:48:35.463229
        Num handles: 1
        Handle: 66
        Count: 2
> ACL Data RX: Handle 66 flags 0x02 dlen 9                                                                                                                                                                                       #466 [hci0] 2022-01-27 18:48:35.463412
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #467 [hci0] 2022-01-27 18:48:35.463934
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0005
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 66 flags 0x00 dlen 11                                                                                                                                                                                      #468 [hci0] 2022-01-27 18:48:35.464812
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0xffff
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 66 flags 0x00 dlen 20                                                                                                                                                                                      #469 [hci0] 2022-01-27 18:48:35.465014
      ATT: Read By Type Response (0x09) len 15
        Attribute data length: 7
        Attribute data list: 2 entries
        Handle: 0x0002
        Value: 020300002a
        Handle: 0x0004
        Value: 020500012a
> ACL Data RX: Handle 66 flags 0x02 dlen 55                                                                                                                                                                                      #470 [hci0] 2022-01-27 18:48:35.583567
      ATT: Read By Type Response (0x09) len 50
        Attribute data length: 7
        Attribute data list: 7 entries
        Handle: 0x0002
        Value: 200300052a
        Handle: 0x0004
        Value: 0205003a2b
        Handle: 0x0006
        Value: 0a0700292b
        Handle: 0x0008
        Value: 0209002a2b
        Handle: 0x0015
        Value: 021600002a
        Handle: 0x0017
        Value: 021800012a
        Handle: 0x0019
        Value: 021a00a62a
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #471 [hci0] 2022-01-27 18:48:35.583812
        Num handles: 1
        Handle: 66
        Count: 2
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #472 [hci0] 2022-01-27 18:48:35.584000
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0005-0x0005
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 66 flags 0x00 dlen 11                                                                                                                                                                                      #473 [hci0] 2022-01-27 18:48:35.584988
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x001a-0xffff
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #474 [hci0] 2022-01-27 18:48:35.585406
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0005
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 9                                                                                                                                                                                       #475 [hci0] 2022-01-27 18:48:35.605502
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x001a
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #476 [hci0] 2022-01-27 18:48:35.606226
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0006-0x0009
        Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #477 [hci0] 2022-01-27 18:48:35.606235
        Num handles: 1
        Handle: 66
        Count: 2
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #478 [hci0] 2022-01-27 18:48:35.607321
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x001b-0xffff
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #479 [hci0] 2022-01-27 18:48:35.607350
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0006
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 9                                                                                                                                                                                       #480 [hci0] 2022-01-27 18:48:35.635493
      ATT: Error Response (0x01) len 4
        Find Information Request (0x04)
        Handle: 0x001b
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #481 [hci0] 2022-01-27 18:48:35.636240
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0006-0x0009
        Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #482 [hci0] 2022-01-27 18:48:35.636248
        Num handles: 1
        Handle: 66
        Count: 2
< ACL Data TX: Handle 66 flags 0x00 dlen 13                                                                                                                                                                                      #483 [hci0] 2022-01-27 18:48:35.639619
      ATT: Read By Type Response (0x09) len 8
        Attribute data length: 7
        Attribute data list: 1 entry
        Handle: 0x0007
        Value: 200800052a
< ACL Data TX: Handle 66 flags 0x00 dlen 7                                                                                                                                                                                       #484 [hci0] 2022-01-27 18:48:35.639645
      ATT: Read Request (0x0a) len 2
        Handle: 0x0016
> ACL Data RX: Handle 66 flags 0x02 dlen 11                                                                                                                                                                                      #485 [hci0] 2022-01-27 18:48:35.650506
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0008-0x0009
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 66 flags 0x00 dlen 9                                                                                                                                                                                       #486 [hci0] 2022-01-27 18:48:35.650878
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0008
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #487 [hci0] 2022-01-27 18:48:35.650984
        Num handles: 1
        Handle: 66
        Count: 2
> ACL Data RX: Handle 66 flags 0x02 dlen 16                                                                                                                                                                                      #488 [hci0] 2022-01-27 18:48:35.650989
      ATT: Read Response (0x0b) len 11
        Value: 506978656c20362050726f
< ACL Data TX: Handle 66 flags 0x00 dlen 7                                                                                                                                                                                       #489 [hci0] 2022-01-27 18:48:35.651724
      ATT: Read Request (0x0a) len 2
        Handle: 0x0018
> ACL Data RX: Handle 66 flags 0x02 dlen 9                                                                                                                                                                                       #490 [hci0] 2022-01-27 18:48:35.680491
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
< ACL Data TX: Handle 66 flags 0x00 dlen 10                                                                                                                                                                                      #491 [hci0] 2022-01-27 18:48:35.680875
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
> ACL Data RX: Handle 66 flags 0x02 dlen 7                                                                                                                                                                                       #492 [hci0] 2022-01-27 18:48:35.680987
      ATT: Read Response (0x0b) len 2
        Value: 0000
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #493 [hci0] 2022-01-27 18:48:35.681440
        Num handles: 1
        Handle: 66
        Count: 2
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                                                        #494 [hci0] 2022-01-27 18:48:35.695915
      LE Remote Connection Parameter Request (0x06)
        Handle: 66
        Min connection interval: 45.00 msec (0x0024)
        Max connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
< HCI Command: LE Remote Connection Parameter Request Reply (0x08|0x0020) plen 14                                                                                                                                                #495 [hci0] 2022-01-27 18:48:35.695968
        Handle: 66
        Min connection interval: 45.00 msec (0x0024)
        Max connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Min connection length: 0.000 msec (0x0000)
        Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Complete (0x0e) plen 6                                                                                                                                                                                      #496 [hci0] 2022-01-27 18:48:35.698626
      LE Remote Connection Parameter Request Reply (0x08|0x0020) ncmd 1
        Status: Success (0x00)
        Handle: 66
> HCI Event: LE Meta Event (0x3e) plen 10                                                                                                                                                                                        #497 [hci0] 2022-01-27 18:48:35.800863
      LE Connection Update Complete (0x03)
        Status: Success (0x00)
        Handle: 66
        Connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                                                           #498 [hci0] 2022-01-27 18:48:35.893590
        Num handles: 1
        Handle: 66
        Count: 1
< HCI Command: LE Set Advertising Parameters (0x08|0x0006) plen 15                                                                                                                                                               #499 [hci0] 2022-01-27 18:48:36.562349
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Type: Connectable undirected - ADV_IND (0x00)
        Own address type: Public (0x00)
        Direct address type: Public (0x00)
        Direct address: 00:00:00:00:00:00 (OUI 00-00-00)
        Channel map: 37, 38, 39 (0x07)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                                                      #500 [hci0] 2022-01-27 18:48:36.562683
      LE Set Advertising Parameters (0x08|0x0006) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1                                                                                                                                                                      #501 [hci0] 2022-01-27 18:48:36.562736
        Advertising: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                                                      #502 [hci0] 2022-01-27 18:48:36.563148
      LE Set Advertise Enable (0x08|0x000a) ncmd 1
        Status: Success (0x00)

bluetoothctl output

[NEW] Device 5F:CC:1C:FA:EC:50 5F-CC-1C-FA-EC-50
[NEW] Primary Service
	/org/bluez/hci0/dev_5F_CC_1C_FA_EC_50/service0001
	00001801-0000-1000-8000-00805f9b34fb
	Generic Attribute Profile
[NEW] Characteristic
	/org/bluez/hci0/dev_5F_CC_1C_FA_EC_50/service0001/char0002
	00002a05-0000-1000-8000-00805f9b34fb
	Service Changed
[NEW] Characteristic
	/org/bluez/hci0/dev_5F_CC_1C_FA_EC_50/service0001/char0004
	00002b3a-0000-1000-8000-00805f9b34fb
	Unknown
[NEW] Characteristic
	/org/bluez/hci0/dev_5F_CC_1C_FA_EC_50/service0001/char0006
	00002b29-0000-1000-8000-00805f9b34fb
	Unknown
[NEW] Characteristic
	/org/bluez/hci0/dev_5F_CC_1C_FA_EC_50/service0001/char0008
	00002b2a-0000-1000-8000-00805f9b34fb
	Unknown
[CHG] Device 5F:CC:1C:FA:EC:50 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Device 5F:CC:1C:FA:EC:50 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Device 5F:CC:1C:FA:EC:50 ServicesResolved: yes
[CHG] Device 5F:CC:1C:FA:EC:50 Name: Pixel 6 Pro
[CHG] Device 5F:CC:1C:FA:EC:50 Alias: Pixel 6 Pro

journal output including bluetoothd logs

Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/adapter.c:connected_callback() hci0 device 44:AF:A0:2E:DB:CF connected eir_len 0
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/device.c:device_create() dst 44:AF:A0:2E:DB:CF
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/device.c:device_new() address 44:AF:A0:2E:DB:CF
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/gatt-database.c:connect_cb() New incoming LE ATT connection
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: attrib/gattrib.c:g_attrib_ref() 0x157a878: g_attrib_ref=1
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/device.c:load_gatt_db() Restoring 44:AF:A0:2E:DB:CF gatt database from file
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: No cache for 44:AF:A0:2E:DB:CF
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/gatt-client.c:btd_gatt_client_connected() Device connected.
Jan 27 18:48:34 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0xffff
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() MTU exchange complete, with MTU: 517
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Grp Type - start: 0x0001 end: 0xffff
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Primary services found: 2
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0001, end: 0x0009, uuid: 00001801-0000-1000-8000-00805f9b34fb
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0014, end: 0xffff, uuid: 00001800-0000-1000-8000-00805f9b34fb
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Grp Type - start: 0x000a end: 0xffff
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0005
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0005
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0005 end: 0x0005
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Characteristics found: 7
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0002, end: 0x0003, value: 0x0003, props: 0x20, uuid: 00002a05-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0004, end: 0x0005, value: 0x0005, props: 0x02, uuid: 00002b3a-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0006, end: 0x0007, value: 0x0007, props: 0x0a, uuid: 00002b29-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0008, end: 0x0014, value: 0x0009, props: 0x02, uuid: 00002b2a-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0015, end: 0x0016, value: 0x0016, props: 0x02, uuid: 00002a00-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0017, end: 0x0018, value: 0x0018, props: 0x02, uuid: 00002a01-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() start: 0x0019, end: 0xffff, value: 0x001a, props: 0x02, uuid: 00002aa6-0000-1
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0006 end: 0x0009
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Registered handler for "Service Changed": 0
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_client_ready_cb() status: success, error: 0
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:device_probe_profiles() Probing profiles for device 44:AF:A0:2E:DB:CF
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:device_probe_profiles() Probing profiles for device 44:AF:A0:2E:DB:CF
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: profiles/gap/gas.c:gap_probe() GAP profile probe (44:AF:A0:2E:DB:CF)
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/service.c:change_state() 0x15733e8: device 44:AF:A0:2E:DB:CF profile gap-profile state changed: unavailable -> disconnected (0)
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: profiles/gap/gas.c:gap_accept() GAP profile accept (44:AF:A0:2E:DB:CF)
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: profiles/gap/gas.c:handle_characteristic() Unsupported characteristic: 00002aa6-0000-1000-8000-00805f9b34fb
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/service.c:change_state() 0x15733e8: device 44:AF:A0:2E:DB:CF profile gap-profile state changed: disconnected -> connected (0)
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:btd_gatt_client_ready() GATT client ready
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:create_services() Exporting objects for GATT services: 44:AF:A0:2E:DB:CF
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF/service0001
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF/service0001/char0002
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF/service0001/char0004
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF/service0001/char0006
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF/service0001/char0008
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF err 0
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:store_gatt_db() Can't store GATT db for private addressed device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0006 end: 0x0009
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Read By Type - start: 0x0008 end: 0x0009
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: profiles/gap/gas.c:read_device_name_cb() GAP Device Name: Pixel 6 Pro
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF Pixel 6 Pro
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: src/device.c:gatt_debug() Find Info - start: 0x0009 end: 0x0009
Jan 27 18:48:35 raspberrypi bluetoothd[1147]: profiles/gap/gas.c:read_appearance_cb() GAP Appearance: 0x0000

Client nRF Logs (timestamp from another run)

nRF Connect, 2022-01-27
Hello (DC:A6:32:07:83:53)
D	13:59:26.372	gatt.close()
D	13:59:26.374	wait(200)
V	13:59:26.575	Connecting to DC:A6:32:07:83:53...
D	13:59:26.575	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	13:59:28.245	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	13:59:28.245	Connected to DC:A6:32:07:83:53
V	13:59:28.253	Discovering services...
D	13:59:28.253	gatt.discoverServices()
D	13:59:28.273	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
I	13:59:28.934	Connection parameters updated (interval: 7.5ms, latency: 0, timeout: 5000ms)
D	13:59:29.039	[Callback] Services discovered with status: 0
I	13:59:29.039	Services discovered
V	13:59:29.042	Generic Access (0x1800)
- Device Name [R] (0x2A00)
- Appearance [R] (0x2A01)
Generic Attribute (0x1801)
- Service Changed [I] (0x2A05)
   Client Characteristic Configuration (0x2902)
D	13:59:29.043	gatt.setCharacteristicNotification(00002a05-0000-1000-8000-00805f9b34fb, true)
I	13:59:29.139	Connection parameters updated (interval: 45.0ms, latency: 0, timeout: 5000ms)
V	13:59:47.213	Disconnecting...
D	13:59:47.213	gatt.disconnect()
D	13:59:47.221	[Callback] Connection state changed with status: 0 and new state: DISCONNECTED (0)
I	13:59:47.221	Disconnected
D	13:59:48.272	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED

4.19.118 Logs from Android Pixel 6 Pro - Failed connection 👎

btmon

> HCI Event: LE Meta Event (0x3e) plen 19                                                                                                                                                   #413 [hci0] 2022-01-27 18:51:13.836149
      LE Connection Complete (0x01)
        Status: Success (0x00)
        Handle: 64
        Role: Slave (0x01)
        Peer address type: Random (0x01)
        Peer address: 44:AF:A0:2E:DB:CF (Resolvable)
        Connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Master clock accuracy: 0x01
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0001} [hci0] 2022-01-27 18:51:13.836214
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0002} [hci0] 2022-01-27 18:51:13.836214
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Flags: 0x00000000
        Data length: 0
> HCI Event: Vendor (0xff) plen 5                                                                                                                                                           #414 [hci0] 2022-01-27 18:51:13.836158
        55 00 00 40 00                                   U..@.
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2                                                                                                                            #415 [hci0] 2022-01-27 18:51:13.836459
        Handle: 64
> HCI Event: Command Status (0x0f) plen 4                                                                                                                                                   #416 [hci0] 2022-01-27 18:51:13.840904
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: Command Complete (0x0e) plen 14                                                                                                                                                #417 [hci0] 2022-01-27 18:51:13.840924
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
        00 00 00 00 00 00 00 00 00 00                    ..........
> HCI Event: LE Meta Event (0x3e) plen 12                                                                                                                                                   #418 [hci0] 2022-01-27 18:51:13.969220
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 64
        Features: 0x2f 0x00 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Connection Parameter Request Procedure
          Extended Reject Indication
          Slave-initiated Features Exchange
          LE Data Packet Length Extension
= bluetoothd: No cache for 44:AF:A0:2E:DB:CF                                                                                                                                                            2022-01-27 18:51:13.978212
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #419 [hci0] 2022-01-27 18:51:13.977898
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
< HCI Command: LE Set Advertising Parameters (0x08|0x0006) plen 15                                                                                                                          #420 [hci0] 2022-01-27 18:51:15.525249
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Type: Connectable undirected - ADV_IND (0x00)
        Own address type: Public (0x00)
        Direct address type: Public (0x00)
        Direct address: 00:00:00:00:00:00 (OUI 00-00-00)
        Channel map: 37, 38, 39 (0x07)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #421 [hci0] 2022-01-27 18:51:15.525962
      LE Set Advertising Parameters (0x08|0x0006) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1                                                                                                                                 #422 [hci0] 2022-01-27 18:51:15.526045
        Advertising: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #423 [hci0] 2022-01-27 18:51:15.526433
      LE Set Advertise Enable (0x08|0x000a) ncmd 1
        Status: Success (0x00)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #424 [hci0] 2022-01-27 18:51:19.099861
        Num handles: 1
        Handle: 64
        Count: 1
> HCI Event: Disconnect Complete (0x05) plen 4                                                                                                                                              #425 [hci0] 2022-01-27 18:51:19.100364
        Status: Success (0x00)
        Handle: 64
        Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8                                                                                                                                       {0x0001} [hci0] 2022-01-27 18:51:19.100407
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Reason: Connection timeout (0x01)
@ MGMT Event: Device Disconnected (0x000c) plen 8                                                                                                                                       {0x0002} [hci0] 2022-01-27 18:51:19.100407
        LE Address: 44:AF:A0:2E:DB:CF (Resolvable)
        Reason: Connection timeout (0x01)

journal output including bluetoothd

Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/adapter.c:connected_callback() hci0 device 44:AF:A0:2E:DB:CF connected eir_len 0
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/device.c:device_create() dst 44:AF:A0:2E:DB:CF
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/device.c:device_new() address 44:AF:A0:2E:DB:CF
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/gatt-database.c:connect_cb() New incoming LE ATT connection
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: attrib/gattrib.c:g_attrib_ref() 0x1afe850: g_attrib_ref=1
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/device.c:load_gatt_db() Restoring 44:AF:A0:2E:DB:CF gatt database from file
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: No cache for 44:AF:A0:2E:DB:CF
Jan 27 18:51:13 raspberrypi bluetoothd[1399]: src/gatt-client.c:btd_gatt_client_connected() Device connected.
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/adapter.c:dev_disconnected() Device 44:AF:A0:2E:DB:CF disconnected, reason 1
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/adapter.c:adapter_remove_connection()
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/adapter.c:adapter_remove_connection() Removing temporary device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/device.c:device_remove() Removing device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/device.c:btd_device_unref() Freeing device /org/bluez/hci0/dev_44_AF_A0_2E_DB_CF
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: attrib/gattrib.c:g_attrib_unref() 0x1afe850: g_attrib_unref=0
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/device.c:device_free() 0x1b083b8
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: plugins/policy.c:disconnect_cb() reason 1
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 44:AF:A0:2E:DB:CF type 2 status 0xe
Jan 27 18:51:19 raspberrypi bluetoothd[1399]: src/adapter.c:resume_discovery()

nRF Client log:

nRF Connect, 2022-01-27
Hello (DC:A6:32:05:89:E9)
D	14:01:24.087	gatt.close()
D	14:01:24.089	wait(200)
V	14:01:24.289	Connecting to DC:A6:32:05:89:E9...
D	14:01:24.290	gatt = device.connectGatt(autoConnect = false, TRANSPORT_LE, preferred PHY = LE 1M)
D	14:01:26.867	[Callback] Connection state changed with status: 0 and new state: CONNECTED (2)
I	14:01:26.867	Connected to DC:A6:32:05:89:E9
V	14:01:26.880	Discovering services...
D	14:01:26.880	gatt.discoverServices()
D	14:01:26.895	[Broadcast] Action received: android.bluetooth.device.action.ACL_CONNECTED
D	14:01:31.924	[Callback] Connection state changed with status: 0 and new state: DISCONNECTED (0)
I	14:01:31.924	Disconnected
D	14:01:31.938	[Broadcast] Action received: android.bluetooth.device.action.ACL_DISCONNECTED

Not every BLE connection fails to 4.19.118, below are btmon logs from connections from iPhone 6s, Android Pixel 1 and another Raspi connecting via bluetoothctl.

4.19.118 Logs from Android Pixel 1 - Successful connection 👍

btmon

@ RAW Open: hciconfig (privileged) version 2.22                                                                                                                                                {0x0003} 2022-01-27 19:04:28.401958
@ RAW Close: hciconfig                                                                                                                                                                         {0x0003} 2022-01-27 19:04:28.402861
> HCI Event: LE Meta Event (0x3e) plen 19                                                                                                                                                   #439 [hci0] 2022-01-27 19:05:03.348010
      LE Connection Complete (0x01)
        Status: Success (0x00)
        Handle: 64
        Role: Slave (0x01)
        Peer address type: Random (0x01)
        Peer address: 5B:47:E4:29:9E:E1 (Resolvable)
        Connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Master clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0001} [hci0] 2022-01-27 19:05:03.348073
        LE Address: 5B:47:E4:29:9E:E1 (Resolvable)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0002} [hci0] 2022-01-27 19:05:03.348073
        LE Address: 5B:47:E4:29:9E:E1 (Resolvable)
        Flags: 0x00000000
        Data length: 0
> HCI Event: Vendor (0xff) plen 5                                                                                                                                                           #440 [hci0] 2022-01-27 19:05:03.348019
        55 00 00 40 00                                   U..@.
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2                                                                                                                            #441 [hci0] 2022-01-27 19:05:03.348297
        Handle: 64
> HCI Event: Command Status (0x0f) plen 4                                                                                                                                                   #442 [hci0] 2022-01-27 19:05:03.350197
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: Command Complete (0x0e) plen 14                                                                                                                                                #443 [hci0] 2022-01-27 19:05:03.350206
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
        00 00 00 00 00 00 00 00 00 00                    ..........
< HCI Command: LE Set Advertising Parameters (0x08|0x0006) plen 15                                                                                                                          #444 [hci0] 2022-01-27 19:05:03.365304
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Type: Connectable undirected - ADV_IND (0x00)
        Own address type: Public (0x00)
        Direct address type: Public (0x00)
        Direct address: 00:00:00:00:00:00 (OUI 00-00-00)
        Channel map: 37, 38, 39 (0x07)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #445 [hci0] 2022-01-27 19:05:03.365639
      LE Set Advertising Parameters (0x08|0x0006) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1                                                                                                                                 #446 [hci0] 2022-01-27 19:05:03.365687
        Advertising: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #447 [hci0] 2022-01-27 19:05:03.366229
      LE Set Advertise Enable (0x08|0x000a) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12                                                                                                                                                   #448 [hci0] 2022-01-27 19:05:03.471173
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 64
        Features: 0x3f 0x00 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Connection Parameter Request Procedure
          Extended Reject Indication
          Slave-initiated Features Exchange
          LE Ping
          LE Data Packet Length Extension
= bluetoothd: No cache for 5B:47:E4:29:9E:E1                                                                                                                                                            2022-01-27 19:05:03.479761
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #449 [hci0] 2022-01-27 19:05:03.479705
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                   #450 [hci0] 2022-01-27 19:05:03.516214
      LE Data Length Change (0x07)
        Handle: 64
        Max TX octets: 123
        Max TX time: 1096
        Max RX octets: 123
        Max RX time: 1096
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #451 [hci0] 2022-01-27 19:05:03.875875
      ATT: Exchange MTU Response (0x03) len 2
        Server RX MTU: 517
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #452 [hci0] 2022-01-27 19:05:03.876558
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #453 [hci0] 2022-01-27 19:05:03.877279
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
< ACL Data TX: Handle 64 flags 0x00 dlen 18                                                                                                                                                 #454 [hci0] 2022-01-27 19:05:03.877707
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #455 [hci0] 2022-01-27 19:05:04.007887
        Num handles: 1
        Handle: 64
        Count: 1
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #456 [hci0] 2022-01-27 19:05:04.012050
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 18                                                                                                                                                 #457 [hci0] 2022-01-27 19:05:04.055890
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0003
        UUID: Generic Attribute Profile (0x1801)
        Handle range: 0x0014-0x001a
        UUID: Generic Access Profile (0x1800)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #458 [hci0] 2022-01-27 19:05:04.056179
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x001b-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #459 [hci0] 2022-01-27 19:05:04.056457
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #460 [hci0] 2022-01-27 19:05:04.056875
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x000a
        Error: Attribute Not Found (0x0a)
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                   #461 [hci0] 2022-01-27 19:05:04.101243
      LE Remote Connection Parameter Request (0x06)
        Handle: 64
        Min connection interval: 7.50 msec (0x0006)
        Max connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
< HCI Command: LE Remote Connection Parameter Request Reply (0x08|0x0020) plen 14                                                                                                           #462 [hci0] 2022-01-27 19:05:04.101318
        Handle: 64
        Min connection interval: 7.50 msec (0x0006)
        Max connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Min connection length: 0.000 msec (0x0000)
        Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Complete (0x0e) plen 6                                                                                                                                                 #463 [hci0] 2022-01-27 19:05:04.104260
      LE Remote Connection Parameter Request Reply (0x08|0x0020) ncmd 1
        Status: Success (0x00)
        Handle: 64
> ACL Data RX: Handle 64 flags 0x02 dlen 46                                                                                                                                                 #464 [hci0] 2022-01-27 19:05:04.236433
      ATT: Read By Group Type Response (0x11) len 41
        Attribute data length: 20
        Attribute group list: 2 entries
        Handle range: 0x0028-0x0033
        UUID: Vendor specific (16bcfd00-253f-c348-e831-0db3e334d580)
        Handle range: 0x0034-0xffff
        UUID: Vendor specific (abbafc00-e56a-484c-b832-8b17cf6cbfe8)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #465 [hci0] 2022-01-27 19:05:04.236801
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0005
        Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #466 [hci0] 2022-01-27 19:05:04.236809
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #467 [hci0] 2022-01-27 19:05:04.238865
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #468 [hci0] 2022-01-27 19:05:04.238916
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Secondary Service (0x2801)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #469 [hci0] 2022-01-27 19:05:04.460945
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0005
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #470 [hci0] 2022-01-27 19:05:04.461303
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0001
        Error: Unsupported Group Type (0x10)
< ACL Data TX: Handle 64 flags 0x00 dlen 20                                                                                                                                                 #471 [hci0] 2022-01-27 19:05:04.461494
      ATT: Read By Type Response (0x09) len 15
        Attribute data length: 7
        Attribute data list: 2 entries
        Handle: 0x0002
        Value: 020300002a
        Handle: 0x0004
        Value: 020500012a
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #472 [hci0] 2022-01-27 19:05:04.461566
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0xffff
        Attribute type: Include (0x2802)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #473 [hci0] 2022-01-27 19:05:04.461758
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #474 [hci0] 2022-01-27 19:05:04.595905
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0005-0x0005
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #475 [hci0] 2022-01-27 19:05:04.596292
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #476 [hci0] 2022-01-27 19:05:04.596416
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0005
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #477 [hci0] 2022-01-27 19:05:04.596555
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0xffff
        Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #478 [hci0] 2022-01-27 19:05:04.596915
        Num handles: 1
        Handle: 64
        Count: 2
> HCI Event: LE Meta Event (0x3e) plen 10                                                                                                                                                   #479 [hci0] 2022-01-27 19:05:04.693741
      LE Connection Update Complete (0x03)
        Status: Success (0x00)
        Handle: 64
        Connection interval: 7.50 msec (0x0006)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #480 [hci0] 2022-01-27 19:05:04.700905
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0006-0x0009
        Attribute type: Include (0x2802)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #481 [hci0] 2022-01-27 19:05:04.701318
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0006
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #482 [hci0] 2022-01-27 19:05:04.701440
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 34                                                                                                                                                 #483 [hci0] 2022-01-27 19:05:04.701674
      ATT: Read By Type Response (0x09) len 29
        Attribute data length: 7
        Attribute data list: 4 entries
        Handle: 0x0002
        Value: 200300052a
        Handle: 0x0015
        Value: 021600002a
        Handle: 0x0017
        Value: 021800012a
        Handle: 0x0019
        Value: 021a00a62a
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #484 [hci0] 2022-01-27 19:05:04.702148
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x001a-0xffff
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #485 [hci0] 2022-01-27 19:05:04.715906
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0006-0x0009
        Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #486 [hci0] 2022-01-27 19:05:04.716202
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 13                                                                                                                                                 #487 [hci0] 2022-01-27 19:05:04.716739
      ATT: Read By Type Response (0x09) len 8
        Attribute data length: 7
        Attribute data list: 1 entry
        Handle: 0x0007
        Value: 200800052a
> ACL Data RX: Handle 64 flags 0x02 dlen 123                                                                                                                                                #488 [hci0] 2022-01-27 19:05:04.724726
> ACL Data RX: Handle 64 flags 0x01 dlen 30                                                                                                                                                 #489 [hci0] 2022-01-27 19:05:04.725088
      ATT: Read By Type Response (0x09) len 148
        Attribute data length: 21
        Attribute data list: 7 entries
        Handle: 0x0029
        Value: 122a0080d534e3b30d31e848c33f2504fdbc16
        Handle: 0x002c
        Value: 102d0080d534e3b30d31e848c33f2502fdbc16
        Handle: 0x002f
        Value: 08300080d534e3b30d31e848c33f2501fdbc16
        Handle: 0x0031
        Value: 10320080d534e3b30d31e848c33f2503fdbc16
        Handle: 0x0035
        Value: 123600e8bf6ccf178b32b84c486ae501fcbaab
        Handle: 0x0038
        Value: 123900e8bf6ccf178b32b84c486ae502fcbaab
        Handle: 0x003b
        Value: 103c00e8bf6ccf178b32b84c486ae503fcbaab
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #490 [hci0] 2022-01-27 19:05:04.725402
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x003c-0xffff
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #491 [hci0] 2022-01-27 19:05:04.730793
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0008-0x0009
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #492 [hci0] 2022-01-27 19:05:04.731146
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0008
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #493 [hci0] 2022-01-27 19:05:04.738339
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x003c
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #494 [hci0] 2022-01-27 19:05:04.739054
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #495 [hci0] 2022-01-27 19:05:04.739728
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x002b-0x002b
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #496 [hci0] 2022-01-27 19:05:04.745820
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
< ACL Data TX: Handle 64 flags 0x00 dlen 10                                                                                                                                                 #497 [hci0] 2022-01-27 19:05:04.746519
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #498 [hci0] 2022-01-27 19:05:04.753346
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x002b
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #499 [hci0] 2022-01-27 19:05:04.753737
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #500 [hci0] 2022-01-27 19:05:04.754175
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x002e-0x002e
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                   #501 [hci0] 2022-01-27 19:05:04.761297
      LE Remote Connection Parameter Request (0x06)
        Handle: 64
        Min connection interval: 45.00 msec (0x0024)
        Max connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
< HCI Command: LE Remote Connection Parameter Request Reply (0x08|0x0020) plen 14                                                                                                           #502 [hci0] 2022-01-27 19:05:04.761368
        Handle: 64
        Min connection interval: 45.00 msec (0x0024)
        Max connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
        Min connection length: 0.000 msec (0x0000)
        Max connection length: 0.000 msec (0x0000)
> HCI Event: Command Complete (0x0e) plen 6                                                                                                                                                 #503 [hci0] 2022-01-27 19:05:04.764304
      LE Remote Connection Parameter Request Reply (0x08|0x0020) ncmd 1
        Status: Success (0x00)
        Handle: 64
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #504 [hci0] 2022-01-27 19:05:04.768605
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x002e
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #505 [hci0] 2022-01-27 19:05:04.768971
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #506 [hci0] 2022-01-27 19:05:04.769877
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0033-0x0033
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #507 [hci0] 2022-01-27 19:05:04.805820
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0033
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #508 [hci0] 2022-01-27 19:05:04.806406
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0037-0x0037
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #509 [hci0] 2022-01-27 19:05:04.821044
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0037
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #510 [hci0] 2022-01-27 19:05:04.821221
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #511 [hci0] 2022-01-27 19:05:04.821682
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x003a-0x003a
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #512 [hci0] 2022-01-27 19:05:04.835817
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x003a
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #513 [hci0] 2022-01-27 19:05:04.836240
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x003d-0xffff
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #514 [hci0] 2022-01-27 19:05:04.865929
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x003d
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #515 [hci0] 2022-01-27 19:05:04.866166
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x003e-0xffff
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #516 [hci0] 2022-01-27 19:05:04.866490
        Num handles: 1
        Handle: 64
        Count: 2
> HCI Event: LE Meta Event (0x3e) plen 10                                                                                                                                                   #517 [hci0] 2022-01-27 19:05:04.866725
      LE Connection Update Complete (0x03)
        Status: Success (0x00)
        Handle: 64
        Connection interval: 45.00 msec (0x0024)
        Connection latency: 0 (0x0000)
        Supervision timeout: 5000 msec (0x01f4)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #518 [hci0] 2022-01-27 19:05:04.955815
      ATT: Error Response (0x01) len 4
        Find Information Request (0x04)
        Handle: 0x003e
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #519 [hci0] 2022-01-27 19:05:04.967425
      ATT: Read Request (0x0a) len 2
        Handle: 0x0016
> ACL Data RX: Handle 64 flags 0x02 dlen 22                                                                                                                                                 #520 [hci0] 2022-01-27 19:05:05.046192
      ATT: Read Response (0x0b) len 17
        Value: 48616e646572736f6e277320506978656c
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #521 [hci0] 2022-01-27 19:05:05.046427
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #522 [hci0] 2022-01-27 19:05:05.046797
      ATT: Read Request (0x0a) len 2
        Handle: 0x0018
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #523 [hci0] 2022-01-27 19:05:05.180825
      ATT: Read Response (0x0b) len 2
        Value: 0000
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #524 [hci0] 2022-01-27 19:05:05.382949
        Num handles: 1
        Handle: 64
        Count: 1

Journal:

Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/adapter.c:connected_callback() hci0 device 5B:47:E4:29:9E:E1 connected eir_len 0
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:device_create() dst 5B:47:E4:29:9E:E1
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:device_new() address 5B:47:E4:29:9E:E1
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_5B_47_E4_29_9E_E1
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/gatt-database.c:connect_cb() New incoming LE ATT connection
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: attrib/gattrib.c:g_attrib_ref() 0x1b025f8: g_attrib_ref=1
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:load_gatt_db() Restoring 5B:47:E4:29:9E:E1 gatt database from file
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: No cache for 5B:47:E4:29:9E:E1
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/gatt-client.c:btd_gatt_client_connected() Device connected.
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() MTU exchange complete, with MTU: 517
Jan 27 19:05:03 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Grp Type - start: 0x0001 end: 0xffff
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Grp Type - start: 0x000a end: 0xffff
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Primary services found: 4
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0001, end: 0x0003, uuid: 00001801-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0014, end: 0x001a, uuid: 00001800-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0028, end: 0x0033, uuid: 16bcfd00-253f-c348-e831-0db3e334d580
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0034, end: 0xffff, uuid: abbafc00-e56a-484c-b832-8b17cf6cbfe8
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0005
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0005
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0005 end: 0x0005
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0006 end: 0x0009
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0006 end: 0x0009
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0008 end: 0x0009
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Characteristics found: 11
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0002, end: 0x0014, value: 0x0003, props: 0x20, uuid: 00002a05-0000-1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0015, end: 0x0016, value: 0x0016, props: 0x02, uuid: 00002a00-0000-1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0017, end: 0x0018, value: 0x0018, props: 0x02, uuid: 00002a01-0000-1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0019, end: 0x0028, value: 0x001a, props: 0x02, uuid: 00002aa6-0000-1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0029, end: 0x002b, value: 0x002a, props: 0x12, uuid: 16bcfd04-253f-c
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x002c, end: 0x002e, value: 0x002d, props: 0x10, uuid: 16bcfd02-253f-c
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x002f, end: 0x0030, value: 0x0030, props: 0x08, uuid: 16bcfd01-253f-c
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0031, end: 0x0034, value: 0x0032, props: 0x10, uuid: 16bcfd03-253f-c
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0035, end: 0x0037, value: 0x0036, props: 0x12, uuid: abbafc01-e56a-4
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0038, end: 0x003a, value: 0x0039, props: 0x12, uuid: abbafc02-e56a-4
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x003b, end: 0xffff, value: 0x003c, props: 0x10, uuid: abbafc03-e56a-4
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Find Info - start: 0x0009 end: 0x0009
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x002b, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x002e, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0033, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0037, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x003a, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x003d, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Registered handler for "Service Changed": 0
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:gatt_client_ready_cb() status: success, error: 0
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 5B:47:E4:29:9E:E1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 5B:47:E4:29:9E:E1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:gap_probe() GAP profile probe (5B:47:E4:29:9E:E1)
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b0eab8: device 5B:47:E4:29:9E:E1 profile gap-profile state changed: unavailable -> disconnected (0)
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:gap_accept() GAP profile accept (5B:47:E4:29:9E:E1)
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:handle_characteristic() Unsupported characteristic: 00002aa6-0000-1000-8000-00805f9b34fb
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b0eab8: device 5B:47:E4:29:9E:E1 profile gap-profile state changed: disconnected -> connected (0)
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 5B:47:E4:29:9E:E1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 5B:47:E4:29:9E:E1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:btd_gatt_client_ready() GATT client ready
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:create_services() Exporting objects for GATT services: 5B:47:E4:29:9E:E1
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0001
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0001/char0002
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0029
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0029/desc002b
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002c
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002c/desc002e
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002f
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0031
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0031/desc0033
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0035
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0035/desc0037
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0038
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0038/desc003a
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char003b
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char003b/desc003d
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_5B_47_E4_29_9E_E1 err 0
Jan 27 19:05:04 raspberrypi bluetoothd[1399]: src/device.c:store_gatt_db() Can't store GATT db for private addressed device /org/bluez/hci0/dev_5B_47_E4_29_9E_E1
Jan 27 19:05:05 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:read_device_name_cb() GAP Device Name: Handerson's Pixel
Jan 27 19:05:05 raspberrypi bluetoothd[1399]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_5B_47_E4_29_9E_E1 Handerson's Pixel
Jan 27 19:05:05 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:read_appearance_cb() GAP Appearance: 0x0000
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/adapter.c:dev_disconnected() Device 5B:47:E4:29:9E:E1 disconnected, reason 3
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/adapter.c:adapter_remove_connection()
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/adapter.c:adapter_remove_connection() Removing temporary device /org/bluez/hci0/dev_5B_47_E4_29_9E_E1
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/device.c:device_remove() Removing device /org/bluez/hci0/dev_5B_47_E4_29_9E_E1
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b0eab8: device 5B:47:E4:29:9E:E1 profile gap-profile state changed: connected -> disconnected (-103)
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b0eab8: device 5B:47:E4:29:9E:E1 profile gap-profile state changed: disconnected -> unavailable (0)
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:gap_remove() GAP profile remove (5B:47:E4:29:9E:E1)
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/service.c:btd_service_unref() 0x1b0eab8: ref=0
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/device.c:btd_device_unref() Freeing device /org/bluez/hci0/dev_5B_47_E4_29_9E_E1
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_service() Removing GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0001
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0001/char0002
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_service() Removing GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0029
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0029/desc002b
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002c
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002c/desc002e
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char002f
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0031
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0028/char0031/desc0033
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_service() Removing GATT service: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0035
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0035/desc0037
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0038
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char0038/desc003a
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_characteristic() Removing GATT characteristic: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char003b
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/gatt-client.c:unregister_descriptor() Removing GATT descriptor: /org/bluez/hci0/dev_5B_47_E4_29_9E_E1/service0034/char003b/desc003d
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: attrib/gattrib.c:g_attrib_unref() 0x1b025f8: g_attrib_unref=0
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/device.c:device_free() 0x1b0f338
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: plugins/policy.c:disconnect_cb() reason 3
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 5B:47:E4:29:9E:E1 type 2 status 0xe
Jan 27 19:05:32 raspberrypi bluetoothd[1399]: src/adapter.c:resume_discovery()

4.19.118 Logs from iPhone 6s - Successful connection 👍

btmon

> HCI Event: LE Meta Event (0x3e) plen 19                                                                                                                                                   #526 [hci0] 2022-01-27 19:07:45.446136
      LE Connection Complete (0x01)
        Status: Success (0x00)
        Handle: 65
        Role: Slave (0x01)
        Peer address type: Random (0x01)
        Peer address: 7A:44:FF:BF:A7:03 (Resolvable)
        Connection interval: 30.00 msec (0x0018)
        Connection latency: 0 (0x0000)
        Supervision timeout: 720 msec (0x0048)
        Master clock accuracy: 0x05
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0001} [hci0] 2022-01-27 19:07:45.446191
        LE Address: 7A:44:FF:BF:A7:03 (Resolvable)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0002} [hci0] 2022-01-27 19:07:45.446191
        LE Address: 7A:44:FF:BF:A7:03 (Resolvable)
        Flags: 0x00000000
        Data length: 0
> HCI Event: Vendor (0xff) plen 5                                                                                                                                                           #527 [hci0] 2022-01-27 19:07:45.446148
        55 00 00 41 00                                   U..A.
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2                                                                                                                            #528 [hci0] 2022-01-27 19:07:45.446430
        Handle: 65
> HCI Event: Command Status (0x0f) plen 4                                                                                                                                                   #529 [hci0] 2022-01-27 19:07:45.448102
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: Command Complete (0x0e) plen 14                                                                                                                                                #530 [hci0] 2022-01-27 19:07:45.448112
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
        00 00 00 00 00 00 00 00 00 00                    ..........
> HCI Event: LE Meta Event (0x3e) plen 12                                                                                                                                                   #531 [hci0] 2022-01-27 19:07:45.526197
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 65
        Features: 0x1d 0x00 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          Slave-initiated Features Exchange
          LE Ping
= bluetoothd: No cache for 7A:44:FF:BF:A7:03                                                                                                                                                            2022-01-27 19:07:45.535881
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #532 [hci0] 2022-01-27 19:07:45.536175
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
< HCI Command: LE Set Advertising Parameters (0x08|0x0006) plen 15                                                                                                                          #533 [hci0] 2022-01-27 19:07:45.605226
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Type: Connectable undirected - ADV_IND (0x00)
        Own address type: Public (0x00)
        Direct address type: Public (0x00)
        Direct address: 00:00:00:00:00:00 (OUI 00-00-00)
        Channel map: 37, 38, 39 (0x07)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #534 [hci0] 2022-01-27 19:07:45.605589
      LE Set Advertising Parameters (0x08|0x0006) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1                                                                                                                                 #535 [hci0] 2022-01-27 19:07:45.605625
        Advertising: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #536 [hci0] 2022-01-27 19:07:45.606013
      LE Set Advertise Enable (0x08|0x000a) ncmd 1
        Status: Success (0x00)
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #537 [hci0] 2022-01-27 19:07:45.615932
      ATT: Exchange MTU Response (0x03) len 2
        Server RX MTU: 185
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #538 [hci0] 2022-01-27 19:07:45.616740
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 65 flags 0x02 dlen 11                                                                                                                                                 #539 [hci0] 2022-01-27 19:07:45.675865
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
< ACL Data TX: Handle 65 flags 0x00 dlen 18                                                                                                                                                 #540 [hci0] 2022-01-27 19:07:45.676344
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #541 [hci0] 2022-01-27 19:07:45.706361
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 18                                                                                                                                                 #542 [hci0] 2022-01-27 19:07:45.736042
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #543 [hci0] 2022-01-27 19:07:45.736463
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 65 flags 0x02 dlen 11                                                                                                                                                 #544 [hci0] 2022-01-27 19:07:45.736401
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #545 [hci0] 2022-01-27 19:07:45.737007
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x000a
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #546 [hci0] 2022-01-27 19:07:45.766368
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #547 [hci0] 2022-01-27 19:07:45.796209
> ACL Data RX: Handle 65 flags 0x01 dlen 19                                                                                                                                                 #548 [hci0] 2022-01-27 19:07:45.796619
      ATT: Read By Group Type Response (0x11) len 41
        Attribute data length: 20
        Attribute group list: 2 entries
        Handle range: 0x000a-0x000e
        UUID: Vendor specific (d0611e78-bbb4-4591-a5f8-487910ae4366)
        Handle range: 0x000f-0x0013
        UUID: Vendor specific (9fa480e0-4967-4542-9390-d343dc5d04ae)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #549 [hci0] 2022-01-27 19:07:45.797044
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0014-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 65 flags 0x02 dlen 11                                                                                                                                                 #550 [hci0] 2022-01-27 19:07:45.797243
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0006-0x0009
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 65 flags 0x00 dlen 13                                                                                                                                                 #551 [hci0] 2022-01-27 19:07:45.797746
      ATT: Read By Type Response (0x09) len 8
        Attribute data length: 7
        Attribute data list: 1 entry
        Handle: 0x0007
        Value: 200800052a
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #552 [hci0] 2022-01-27 19:07:45.826377
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 24                                                                                                                                                 #553 [hci0] 2022-01-27 19:07:45.856179
      ATT: Read By Group Type Response (0x11) len 19
        Attribute data length: 6
        Attribute group list: 3 entries
        Handle range: 0x0014-0x0017
        UUID: Battery Service (0x180f)
        Handle range: 0x0018-0x001d
        UUID: Current Time Service (0x1805)
        Handle range: 0x001e-0x0022
        UUID: Device Information (0x180a)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #554 [hci0] 2022-01-27 19:07:45.856620
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0023-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #555 [hci0] 2022-01-27 19:07:45.856495
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
< ACL Data TX: Handle 65 flags 0x00 dlen 10                                                                                                                                                 #556 [hci0] 2022-01-27 19:07:45.857138
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #557 [hci0] 2022-01-27 19:07:45.886383
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #558 [hci0] 2022-01-27 19:07:45.916211
> ACL Data RX: Handle 65 flags 0x01 dlen 19                                                                                                                                                 #559 [hci0] 2022-01-27 19:07:45.916612
      ATT: Read By Group Type Response (0x11) len 41
        Attribute data length: 20
        Attribute group list: 2 entries
        Handle range: 0x0023-0x002c
        UUID: Vendor specific (7905f431-b5ce-4e99-a40f-4b1e122d00d0)
        Handle range: 0x002d-0x0038
        UUID: Vendor specific (89d3502b-0f36-433a-8ef4-c502ad55f8dc)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #560 [hci0] 2022-01-27 19:07:45.917329
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0039-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #561 [hci0] 2022-01-27 19:07:45.917299
      ATT: Write Request (0x12) len 4
        Handle: 0x0009
          Data: 0200
< ACL Data TX: Handle 65 flags 0x00 dlen 5                                                                                                                                                  #562 [hci0] 2022-01-27 19:07:45.918726
      ATT: Write Response (0x13) len 0
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #563 [hci0] 2022-01-27 19:07:45.946479
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #564 [hci0] 2022-01-27 19:07:45.975870
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0039
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #565 [hci0] 2022-01-27 19:07:45.979920
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Secondary Service (0x2801)
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #566 [hci0] 2022-01-27 19:07:46.036130
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0038
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #567 [hci0] 2022-01-27 19:07:46.036415
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #568 [hci0] 2022-01-27 19:07:46.037553
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0038
        Attribute type: Include (0x2802)
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #569 [hci0] 2022-01-27 19:07:46.095870
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0038
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #570 [hci0] 2022-01-27 19:07:46.096290
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0038
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #571 [hci0] 2022-01-27 19:07:46.156295
      ATT: Read By Type Response (0x09) len 22
        Attribute data length: 7
        Attribute data list: 3 entries
        Handle: 0x0002
        Value: 020300002a
        Handle: 0x0004
        Value: 020500012a
        Handle: 0x0007
        Value: 200800052a
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #572 [hci0] 2022-01-27 19:07:46.156680
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0008-0x0038
        Attribute type: Characteristic (0x2803)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #573 [hci0] 2022-01-27 19:07:46.156649
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #574 [hci0] 2022-01-27 19:07:46.216217
> ACL Data RX: Handle 65 flags 0x01 dlen 21                                                                                                                                                 #575 [hci0] 2022-01-27 19:07:46.216619
      ATT: Read By Type Response (0x09) len 43
        Attribute data length: 21
        Attribute data list: 2 entries
        Handle: 0x000b
        Value: 980c004900d927ee54ed84914c379a6c556786
        Handle: 0x0010
        Value: 981100cce349c57ba77a91cd43995bb1ad0baf
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #576 [hci0] 2022-01-27 19:07:46.216875
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0011-0x0038
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #577 [hci0] 2022-01-27 19:07:46.276244
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #578 [hci0] 2022-01-27 19:07:46.276525
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x01 dlen 14                                                                                                                                                 #579 [hci0] 2022-01-27 19:07:46.276695
      ATT: Read By Type Response (0x09) len 36
        Attribute data length: 7
        Attribute data list: 5 entries
        Handle: 0x0015
        Value: 121600192a
        Handle: 0x0019
        Value: 121a002b2a
        Handle: 0x001c
        Value: 021d000f2a
        Handle: 0x001f
        Value: 022000292a
        Handle: 0x0021
        Value: 022200242a
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #580 [hci0] 2022-01-27 19:07:46.276925
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0022-0x0038
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 65 flags 0x02 dlen 27                                                                                                                                                 #581 [hci0] 2022-01-27 19:07:46.336222
> ACL Data RX: Handle 65 flags 0x01 dlen 27                                                                                                                                                 #582 [hci0] 2022-01-27 19:07:46.336825
> ACL Data RX: Handle 65 flags 0x01 dlen 27                                                                                                                                                 #583 [hci0] 2022-01-27 19:07:46.337494
> ACL Data RX: Handle 65 flags 0x01 dlen 27                                                                                                                                                 #584 [hci0] 2022-01-27 19:07:46.338181
> ACL Data RX: Handle 65 flags 0x01 dlen 24                                                                                                                                                 #585 [hci0] 2022-01-27 19:07:46.338757
      ATT: Read By Type Response (0x09) len 127
        Attribute data length: 21
        Attribute data list: 6 entries
        Handle: 0x0024
        Value: 882500d9d9aafdbd9b2198a849e145f3d8d169
        Handle: 0x0027
        Value: 102800bd1da299e625588cd94201630d12bf9f
        Handle: 0x002a
        Value: 102b00fb7b7cce6ab344beb54bd624e9c6ea22
        Handle: 0x002e
        Value: 982f00c251caf7560edfb88a4ab157d8813c9b
        Handle: 0x0032
        Value: 98330002c196ba92bb0c9a1f418d80ceab7c2f
        Handle: 0x0036
        Value: 8a3700d7d5bb70a8a3aba6d846ab238cf3b2c6
< ACL Data TX: Handle 65 flags 0x00 dlen 11                                                                                                                                                 #586 [hci0] 2022-01-27 19:07:46.339096
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0037-0x0038
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 65 flags 0x02 dlen 9                                                                                                                                                  #587 [hci0] 2022-01-27 19:07:46.396005
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0038
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #588 [hci0] 2022-01-27 19:07:46.396458
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #589 [hci0] 2022-01-27 19:07:46.398024
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #590 [hci0] 2022-01-27 19:07:46.455897
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #591 [hci0] 2022-01-27 19:07:46.456597
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x000d-0x000e
> ACL Data RX: Handle 65 flags 0x02 dlen 14                                                                                                                                                 #592 [hci0] 2022-01-27 19:07:46.516155
      ATT: Find Information Response (0x05) len 9
        Format: UUID-16 (0x01)
        Handle: 0x000d
        UUID: Characteristic Extended Properties (0x2900)
        Handle: 0x000e
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #593 [hci0] 2022-01-27 19:07:46.516429
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #594 [hci0] 2022-01-27 19:07:46.516782
      ATT: Read Request (0x0a) len 2
        Handle: 0x000d
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #595 [hci0] 2022-01-27 19:07:46.575866
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #596 [hci0] 2022-01-27 19:07:46.576500
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0012-0x0013
> ACL Data RX: Handle 65 flags 0x02 dlen 14                                                                                                                                                 #597 [hci0] 2022-01-27 19:07:46.636171
      ATT: Find Information Response (0x05) len 9
        Format: UUID-16 (0x01)
        Handle: 0x0012
        UUID: Characteristic Extended Properties (0x2900)
        Handle: 0x0013
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #598 [hci0] 2022-01-27 19:07:46.636512
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #599 [hci0] 2022-01-27 19:07:46.637366
      ATT: Read Request (0x0a) len 2
        Handle: 0x0012
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #600 [hci0] 2022-01-27 19:07:46.695874
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #601 [hci0] 2022-01-27 19:07:46.698113
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0017-0x0017
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #602 [hci0] 2022-01-27 19:07:46.756144
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0017
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #603 [hci0] 2022-01-27 19:07:46.756417
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #604 [hci0] 2022-01-27 19:07:46.757601
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x001b-0x001b
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #605 [hci0] 2022-01-27 19:07:46.815902
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x001b
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #606 [hci0] 2022-01-27 19:07:46.817998
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0026-0x0026
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #607 [hci0] 2022-01-27 19:07:46.876154
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0026
        UUID: Characteristic Extended Properties (0x2900)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #608 [hci0] 2022-01-27 19:07:46.876424
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #609 [hci0] 2022-01-27 19:07:46.877356
      ATT: Read Request (0x0a) len 2
        Handle: 0x0026
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #610 [hci0] 2022-01-27 19:07:46.935875
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #611 [hci0] 2022-01-27 19:07:46.936562
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0029-0x0029
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #612 [hci0] 2022-01-27 19:07:46.996157
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0029
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #613 [hci0] 2022-01-27 19:07:46.996717
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x002c-0x002c
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #614 [hci0] 2022-01-27 19:07:46.997779
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #615 [hci0] 2022-01-27 19:07:47.055908
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x002c
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #616 [hci0] 2022-01-27 19:07:47.056494
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0030-0x0031
> ACL Data RX: Handle 65 flags 0x02 dlen 14                                                                                                                                                 #617 [hci0] 2022-01-27 19:07:47.116184
      ATT: Find Information Response (0x05) len 9
        Format: UUID-16 (0x01)
        Handle: 0x0030
        UUID: Characteristic Extended Properties (0x2900)
        Handle: 0x0031
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #618 [hci0] 2022-01-27 19:07:47.116514
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #619 [hci0] 2022-01-27 19:07:47.117115
      ATT: Read Request (0x0a) len 2
        Handle: 0x0030
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #620 [hci0] 2022-01-27 19:07:47.175876
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #621 [hci0] 2022-01-27 19:07:47.176422
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0034-0x0035
> ACL Data RX: Handle 65 flags 0x02 dlen 14                                                                                                                                                 #622 [hci0] 2022-01-27 19:07:47.236183
      ATT: Find Information Response (0x05) len 9
        Format: UUID-16 (0x01)
        Handle: 0x0034
        UUID: Characteristic Extended Properties (0x2900)
        Handle: 0x0035
        UUID: Client Characteristic Configuration (0x2902)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #623 [hci0] 2022-01-27 19:07:47.236522
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #624 [hci0] 2022-01-27 19:07:47.236798
      ATT: Read Request (0x0a) len 2
        Handle: 0x0034
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #625 [hci0] 2022-01-27 19:07:47.295884
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #626 [hci0] 2022-01-27 19:07:47.296429
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0038-0x0038
> ACL Data RX: Handle 65 flags 0x02 dlen 10                                                                                                                                                 #627 [hci0] 2022-01-27 19:07:47.356165
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0038
        UUID: Characteristic Extended Properties (0x2900)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #628 [hci0] 2022-01-27 19:07:47.356460
        Num handles: 1
        Handle: 65
        Count: 2
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #629 [hci0] 2022-01-27 19:07:47.356701
      ATT: Read Request (0x0a) len 2
        Handle: 0x0038
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #630 [hci0] 2022-01-27 19:07:47.415885
      ATT: Read Response (0x0b) len 2
        Value: 0100
< ACL Data TX: Handle 65 flags 0x00 dlen 9                                                                                                                                                  #631 [hci0] 2022-01-27 19:07:47.427791
      ATT: Write Request (0x12) len 4
        Handle: 0x0009
          Data: 0200
> ACL Data RX: Handle 65 flags 0x02 dlen 5                                                                                                                                                  #632 [hci0] 2022-01-27 19:07:47.475999
      ATT: Write Response (0x13) len 0
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #633 [hci0] 2022-01-27 19:07:47.477603
      ATT: Read Request (0x0a) len 2
        Handle: 0x0003
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #634 [hci0] 2022-01-27 19:07:47.476638
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 11                                                                                                                                                 #635 [hci0] 2022-01-27 19:07:47.535905
      ATT: Read Response (0x0b) len 6
        Value: 6950686f6e65
< ACL Data TX: Handle 65 flags 0x00 dlen 7                                                                                                                                                  #636 [hci0] 2022-01-27 19:07:47.536197
      ATT: Read Request (0x0a) len 2
        Handle: 0x0005
> ACL Data RX: Handle 65 flags 0x02 dlen 7                                                                                                                                                  #637 [hci0] 2022-01-27 19:07:47.596000
      ATT: Read Response (0x0b) len 2
        Value: 4000
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #638 [hci0] 2022-01-27 19:07:47.596355
        Num handles: 1
        Handle: 65
        Count: 2
> ACL Data RX: Handle 65 flags 0x02 dlen 11                                                                                                                                                 #639 [hci0] 2022-01-27 19:07:47.685873
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0005
        Attribute type: Device Name (0x2a00)
< ACL Data TX: Handle 65 flags 0x00 dlen 19                                                                                                                                                 #640 [hci0] 2022-01-27 19:07:47.686161
      ATT: Read By Type Response (0x09) len 14
        Attribute data length: 13
        Attribute data list: 1 entry
        Handle: 0x0003
        Value: 7261737062657272797069
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #641 [hci0] 2022-01-27 19:07:47.883824
        Num handles: 1
        Handle: 65
        Count: 1

journal with blutoothd logs

Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/adapter.c:connected_callback() hci0 device 7A:44:FF:BF:A7:03 connected eir_len 0
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:device_create() dst 7A:44:FF:BF:A7:03
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:device_new() address 7A:44:FF:BF:A7:03
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_7A_44_FF_BF_A7_03
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/gatt-database.c:connect_cb() New incoming LE ATT connection
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: attrib/gattrib.c:g_attrib_ref() 0x1af2800: g_attrib_ref=1
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:load_gatt_db() Restoring 7A:44:FF:BF:A7:03 gatt database from file
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: No cache for 7A:44:FF:BF:A7:03
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/gatt-client.c:btd_gatt_client_connected() Device connected.
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() MTU exchange complete, with MTU: 185
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Grp Type - start: 0x0001 end: 0xffff
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Grp Type - start: 0x000a end: 0xffff
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0006 end: 0x0009
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Find Info - start: 0x0009 end: 0x0009
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Write Req - handle: 0x0009
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/gatt-database.c:gatt_ccc_write_cb() CCC write called for handle: 0x0009
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Primary services found: 9
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0001, end: 0x0005, uuid: 00001800-0000-1000-8000-00805f9b34fb
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0006, end: 0x0009, uuid: 00001801-0000-1000-8000-00805f9b34fb
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x000a, end: 0x000e, uuid: d0611e78-bbb4-4591-a5f8-487910ae4366
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x000f, end: 0x0013, uuid: 9fa480e0-4967-4542-9390-d343dc5d04ae
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0014, end: 0x0017, uuid: 0000180f-0000-1000-8000-00805f9b34fb
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0018, end: 0x001d, uuid: 00001805-0000-1000-8000-00805f9b34fb
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x001e, end: 0x0022, uuid: 0000180a-0000-1000-8000-00805f9b34fb
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0023, end: 0x002c, uuid: 7905f431-b5ce-4e99-a40f-4b1e122d00d0
Jan 27 19:07:45 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x002d, end: 0x0038, uuid: 89d3502b-0f36-433a-8ef4-c502ad55f8dc
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Characteristics found: 16
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0002, end: 0x0003, value: 0x0003, props: 0x02, uuid: 00002a00-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0004, end: 0x0006, value: 0x0005, props: 0x02, uuid: 00002a01-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0007, end: 0x000a, value: 0x0008, props: 0x20, uuid: 00002a05-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x000b, end: 0x000f, value: 0x000c, props: 0x98, uuid: 8667556c-9a37-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0010, end: 0x0014, value: 0x0011, props: 0x98, uuid: af0badb1-5b99-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0015, end: 0x0018, value: 0x0016, props: 0x12, uuid: 00002a19-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0019, end: 0x001b, value: 0x001a, props: 0x12, uuid: 00002a2b-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x001c, end: 0x001e, value: 0x001d, props: 0x02, uuid: 00002a0f-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x001f, end: 0x0020, value: 0x0020, props: 0x02, uuid: 00002a29-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0021, end: 0x0023, value: 0x0022, props: 0x02, uuid: 00002a24-0000-1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0024, end: 0x0026, value: 0x0025, props: 0x88, uuid: 69d1d8f3-45e1-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0027, end: 0x0029, value: 0x0028, props: 0x10, uuid: 9fbf120d-6301-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x002a, end: 0x002d, value: 0x002b, props: 0x10, uuid: 22eac6e9-24d6-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x002e, end: 0x0031, value: 0x002f, props: 0x98, uuid: 9b3c81d8-57b1-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0032, end: 0x0035, value: 0x0033, props: 0x98, uuid: 2f7cabce-808d-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() start: 0x0036, end: 0x0038, value: 0x0037, props: 0x8a, uuid: c6b2f38c-23ab-4
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0009, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 2
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x000d, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x000e, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 2
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0012, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0013, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0017, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x001b, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0026, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:46 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0029, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x002c, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 2
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0030, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0031, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 2
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0034, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0035, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() handle: 0x0038, uuid: 00002900-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Ext. prop value: 0x0001
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Value set status: 0
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_client_ready_cb() status: success, error: 0
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:gap_probe() GAP profile probe (7A:44:FF:BF:A7:03)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b06d50: device 7A:44:FF:BF:A7:03 profile gap-profile state changed: unavailable -> disconnected (0)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:gap_accept() GAP profile accept (7A:44:FF:BF:A7:03)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b06d50: device 7A:44:FF:BF:A7:03 profile gap-profile state changed: disconnected -> connected (0)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b079e0: device 7A:44:FF:BF:A7:03 profile deviceinfo state changed: unavailable -> disconnected (0)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/deviceinfo/deviceinfo.c:deviceinfo_accept() deviceinfo profile accept (7A:44:FF:BF:A7:03)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/deviceinfo/deviceinfo.c:handle_characteristic() Unsupported characteristic: 00002a29-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/deviceinfo/deviceinfo.c:handle_characteristic() Unsupported characteristic: 00002a24-0000-1000-8000-00805f9b34fb
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/service.c:change_state() 0x1b079e0: device 7A:44:FF:BF:A7:03 profile deviceinfo state changed: disconnected -> connected (0)
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_probe_profiles() Probing profiles for device 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:btd_gatt_client_ready() GATT client ready
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:create_services() Exporting objects for GATT services: 7A:44:FF:BF:A7:03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0006
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0006/char0007
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0006/char0007/desc0009
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000a
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000a/char000b
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000a/char000b/desc000d
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000a/char000b/desc000e
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000f
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000f/char0010
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000f/char0010/desc0012
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service000f/char0010/desc0013
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0014
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0014/char0015
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0014/char0015/desc0017
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0018
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0018/char0019
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0018/char0019/desc001b
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0018/char001c
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service001e
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service001e/char001f
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service001e/char0021
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char0024
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char0024/desc0026
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char0027
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char0027/desc0029
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char002a
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service0023/char002a/desc002c
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char002e
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char002e/desc0030
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char002e/desc0031
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char0032
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char0032/desc0034
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char0032/desc0035
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char0036
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_7A_44_FF_BF_A7_03/service002d/char0036/desc0038
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_7A_44_FF_BF_A7_03 err 0
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:store_gatt_db() Can't store GATT db for private addressed device /org/bluez/hci0/dev_7A_44_FF_BF_A7_03
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Registered handler for "Service Changed": 1
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:read_device_name_cb() GAP Device Name: iPhone
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_7A_44_FF_BF_A7_03 iPhone
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: profiles/gap/gas.c:read_appearance_cb() GAP Appearance: 0x0040
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0005
Jan 27 19:07:47 raspberrypi bluetoothd[1399]: src/gatt-database.c:gap_device_name_read_cb() GAP Device Name read request

4.19.118 Logs from Raspberry Pi - Successful connection 👍

btmon:

> HCI Event: LE Meta Event (0x3e) plen 19                                                                                                                                                   #753 [hci0] 2022-01-27 19:13:41.391211
      LE Connection Complete (0x01)
        Status: Success (0x00)
        Handle: 64
        Role: Slave (0x01)
        Peer address type: Public (0x00)
        Peer address: DC:A6:32:07:83:53 (OUI DC-A6-32)
        Connection interval: 48.75 msec (0x0027)
        Connection latency: 0 (0x0000)
        Supervision timeout: 420 msec (0x002a)
        Master clock accuracy: 0x01
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0001} [hci0] 2022-01-27 19:13:41.391267
        LE Address: DC:A6:32:07:83:53 (OUI DC-A6-32)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13                                                                                                                                         {0x0002} [hci0] 2022-01-27 19:13:41.391267
        LE Address: DC:A6:32:07:83:53 (OUI DC-A6-32)
        Flags: 0x00000000
        Data length: 0
> HCI Event: Vendor (0xff) plen 5                                                                                                                                                           #754 [hci0] 2022-01-27 19:13:41.391222
        55 00 00 40 00                                   U..@.
< HCI Command: LE Read Remote Used Features (0x08|0x0016) plen 2                                                                                                                            #755 [hci0] 2022-01-27 19:13:41.391514
        Handle: 64
> HCI Event: Command Status (0x0f) plen 4                                                                                                                                                   #756 [hci0] 2022-01-27 19:13:41.405046
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: Command Complete (0x0e) plen 14                                                                                                                                                #757 [hci0] 2022-01-27 19:13:41.405058
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
        00 00 00 00 00 00 00 00 00 00                    ..........
> HCI Event: LE Meta Event (0x3e) plen 12                                                                                                                                                   #758 [hci0] 2022-01-27 19:13:41.537934
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 64
        Features: 0x3f 0x00 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Connection Parameter Request Procedure
          Extended Reject Indication
          Slave-initiated Features Exchange
          LE Ping
          LE Data Packet Length Extension
= bluetoothd: No cache for DC:A6:32:07:83:53                                                                                                                                                            2022-01-27 19:13:41.546409
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #759 [hci0] 2022-01-27 19:13:41.546431
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> HCI Event: LE Meta Event (0x3e) plen 11                                                                                                                                                   #760 [hci0] 2022-01-27 19:13:41.586723
      LE Data Length Change (0x07)
        Handle: 64
        Max TX octets: 251
        Max TX time: 2120
        Max RX octets: 251
        Max RX time: 2120
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #761 [hci0] 2022-01-27 19:13:41.635019
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #762 [hci0] 2022-01-27 19:13:41.635425
      ATT: Exchange MTU Response (0x03) len 2
        Server RX MTU: 517
< HCI Command: LE Set Advertising Parameters (0x08|0x0006) plen 15                                                                                                                          #763 [hci0] 2022-01-27 19:13:41.765259
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Type: Connectable undirected - ADV_IND (0x00)
        Own address type: Public (0x00)
        Direct address type: Public (0x00)
        Direct address: 00:00:00:00:00:00 (OUI 00-00-00)
        Channel map: 37, 38, 39 (0x07)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #764 [hci0] 2022-01-27 19:13:41.765615
      LE Set Advertising Parameters (0x08|0x0006) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Advertise Enable (0x08|0x000a) plen 1                                                                                                                                 #765 [hci0] 2022-01-27 19:13:41.765659
        Advertising: Enabled (0x01)
> HCI Event: Command Complete (0x0e) plen 4                                                                                                                                                 #766 [hci0] 2022-01-27 19:13:41.766047
      LE Set Advertise Enable (0x08|0x000a) ncmd 1
        Status: Success (0x00)
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #767 [hci0] 2022-01-27 19:13:41.927644
      ATT: Exchange MTU Response (0x03) len 2
        Server RX MTU: 517
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #768 [hci0] 2022-01-27 19:13:41.928029
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #769 [hci0] 2022-01-27 19:13:41.928224
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Primary Service (0x2800)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #770 [hci0] 2022-01-27 19:13:41.928234
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 18                                                                                                                                                 #771 [hci0] 2022-01-27 19:13:41.928594
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
> ACL Data RX: Handle 64 flags 0x02 dlen 18                                                                                                                                                 #772 [hci0] 2022-01-27 19:13:42.025353
      ATT: Read By Group Type Response (0x11) len 13
        Attribute data length: 6
        Attribute group list: 2 entries
        Handle range: 0x0001-0x0005
        UUID: Generic Access Profile (0x1800)
        Handle range: 0x0006-0x0009
        UUID: Generic Attribute Profile (0x1801)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #773 [hci0] 2022-01-27 19:13:42.025666
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #774 [hci0] 2022-01-27 19:13:42.025798
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x000a-0xffff
        Attribute group type: Primary Service (0x2800)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #775 [hci0] 2022-01-27 19:13:42.025805
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #776 [hci0] 2022-01-27 19:13:42.026131
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x000a
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #777 [hci0] 2022-01-27 19:13:42.122674
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x000a
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #778 [hci0] 2022-01-27 19:13:42.123310
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Secondary Service (0x2801)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #779 [hci0] 2022-01-27 19:13:42.123458
      ATT: Read By Group Type Request (0x10) len 6
        Handle range: 0x0001-0xffff
        Attribute group type: Secondary Service (0x2801)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #780 [hci0] 2022-01-27 19:13:42.123465
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #781 [hci0] 2022-01-27 19:13:42.123859
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #782 [hci0] 2022-01-27 19:13:42.220186
      ATT: Error Response (0x01) len 4
        Read By Group Type Request (0x10)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #783 [hci0] 2022-01-27 19:13:42.220509
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0009
        Attribute type: Include (0x2802)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #784 [hci0] 2022-01-27 19:13:42.220627
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0009
        Attribute type: Include (0x2802)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #785 [hci0] 2022-01-27 19:13:42.220984
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #786 [hci0] 2022-01-27 19:13:42.221111
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #787 [hci0] 2022-01-27 19:13:42.317683
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0001
        Error: Attribute Not Found (0x0a)
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #788 [hci0] 2022-01-27 19:13:42.318024
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0009
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #789 [hci0] 2022-01-27 19:13:42.318159
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0001-0x0009
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 64 flags 0x00 dlen 27                                                                                                                                                 #790 [hci0] 2022-01-27 19:13:42.318549
      ATT: Read By Type Response (0x09) len 22
        Attribute data length: 7
        Attribute data list: 3 entries
        Handle: 0x0002
        Value: 020300002a
        Handle: 0x0004
        Value: 020500012a
        Handle: 0x0007
        Value: 200800052a
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #791 [hci0] 2022-01-27 19:13:42.318655
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 27                                                                                                                                                 #792 [hci0] 2022-01-27 19:13:42.415469
      ATT: Read By Type Response (0x09) len 22
        Attribute data length: 7
        Attribute data list: 3 entries
        Handle: 0x0002
        Value: 020300002a
        Handle: 0x0004
        Value: 020500012a
        Handle: 0x0007
        Value: 200800052a
< ACL Data TX: Handle 64 flags 0x00 dlen 11                                                                                                                                                 #793 [hci0] 2022-01-27 19:13:42.415779
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0008-0x0009
        Attribute type: Characteristic (0x2803)
> ACL Data RX: Handle 64 flags 0x02 dlen 11                                                                                                                                                 #794 [hci0] 2022-01-27 19:13:42.415912
      ATT: Read By Type Request (0x08) len 6
        Handle range: 0x0008-0x0009
        Attribute type: Characteristic (0x2803)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #795 [hci0] 2022-01-27 19:13:42.416274
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0008
        Error: Attribute Not Found (0x0a)
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #796 [hci0] 2022-01-27 19:13:42.416398
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #797 [hci0] 2022-01-27 19:13:42.512682
      ATT: Error Response (0x01) len 4
        Read By Type Request (0x08)
        Handle: 0x0008
        Error: Attribute Not Found (0x0a)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #798 [hci0] 2022-01-27 19:13:42.513065
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #799 [hci0] 2022-01-27 19:13:42.513312
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 10                                                                                                                                                 #800 [hci0] 2022-01-27 19:13:42.514605
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #801 [hci0] 2022-01-27 19:13:42.514685
      ATT: Find Information Request (0x04) len 4
        Handle range: 0x0009-0x0009
> ACL Data RX: Handle 64 flags 0x02 dlen 10                                                                                                                                                 #802 [hci0] 2022-01-27 19:13:42.610198
      ATT: Find Information Response (0x05) len 5
        Format: UUID-16 (0x01)
        Handle: 0x0009
        UUID: Client Characteristic Configuration (0x2902)
> ACL Data RX: Handle 64 flags 0x02 dlen 9                                                                                                                                                  #803 [hci0] 2022-01-27 19:13:42.610814
      ATT: Write Request (0x12) len 4
        Handle: 0x0009
          Data: 0200
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #804 [hci0] 2022-01-27 19:13:42.610822
        Num handles: 1
        Handle: 64
        Count: 2
< ACL Data TX: Handle 64 flags 0x00 dlen 5                                                                                                                                                  #805 [hci0] 2022-01-27 19:13:42.615131
      ATT: Write Response (0x13) len 0
< ACL Data TX: Handle 64 flags 0x00 dlen 9                                                                                                                                                  #806 [hci0] 2022-01-27 19:13:42.615165
      ATT: Write Request (0x12) len 4
        Handle: 0x0009
          Data: 0200
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #807 [hci0] 2022-01-27 19:13:42.707658
      ATT: Read Request (0x0a) len 2
        Handle: 0x0003
> ACL Data RX: Handle 64 flags 0x02 dlen 5                                                                                                                                                  #808 [hci0] 2022-01-27 19:13:42.708139
      ATT: Write Response (0x13) len 0
< ACL Data TX: Handle 64 flags 0x00 dlen 16                                                                                                                                                 #809 [hci0] 2022-01-27 19:13:42.708238
      ATT: Read Response (0x0b) len 11
        Value: 7261737062657272797069
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #810 [hci0] 2022-01-27 19:13:42.708469
      ATT: Read Request (0x0a) len 2
        Handle: 0x0003
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #811 [hci0] 2022-01-27 19:13:42.708449
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #812 [hci0] 2022-01-27 19:13:42.805159
      ATT: Read Request (0x0a) len 2
        Handle: 0x0005
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #813 [hci0] 2022-01-27 19:13:42.805653
        Num handles: 1
        Handle: 64
        Count: 2
> ACL Data RX: Handle 64 flags 0x02 dlen 16                                                                                                                                                 #814 [hci0] 2022-01-27 19:13:42.805660
      ATT: Read Response (0x0b) len 11
        Value: 7261737062657272797069
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #815 [hci0] 2022-01-27 19:13:42.806607
      ATT: Read Response (0x0b) len 2
        Value: 0000
< ACL Data TX: Handle 64 flags 0x00 dlen 7                                                                                                                                                  #816 [hci0] 2022-01-27 19:13:42.806633
      ATT: Read Request (0x0a) len 2
        Handle: 0x0005
> ACL Data RX: Handle 64 flags 0x02 dlen 7                                                                                                                                                  #817 [hci0] 2022-01-27 19:13:42.902721
      ATT: Read Response (0x0b) len 2
        Value: 0000
> HCI Event: Number of Completed Packets (0x13) plen 5                                                                                                                                      #818 [hci0] 2022-01-27 19:13:42.903037
        Num handles: 1
        Handle: 64
        Count: 2

bluetoothctl

[NEW] Device DC:A6:32:07:83:53 DC-A6-32-07-83-53
[NEW] Primary Service
	/org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006
	00001801-0000-1000-8000-00805f9b34fb
	Generic Attribute Profile
[NEW] Characteristic
	/org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006/char0007
	00002a05-0000-1000-8000-00805f9b34fb
	Service Changed
[NEW] Descriptor
	/org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006/char0007/desc0009
	00002902-0000-1000-8000-00805f9b34fb
	Client Characteristic Configuration
[CHG] Device DC:A6:32:07:83:53 UUIDs: 00001800-0000-1000-8000-00805f9b34fb
[CHG] Device DC:A6:32:07:83:53 UUIDs: 00001801-0000-1000-8000-00805f9b34fb
[CHG] Device DC:A6:32:07:83:53 ServicesResolved: yes
[CHG] Device DC:A6:32:07:83:53 Name: raspberrypi
[CHG] Device DC:A6:32:07:83:53 Alias: raspberrypi
[DC-A6-32-07-83-53]#

journal

Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/adapter.c:connected_callback() hci0 device DC:A6:32:07:83:53 connected eir_len 0
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:device_create() dst DC:A6:32:07:83:53
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:device_new() address DC:A6:32:07:83:53
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:device_new() Creating device /org/bluez/hci0/dev_DC_A6_32_07_83_53
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/gatt-database.c:connect_cb() New incoming LE ATT connection
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: attrib/gattrib.c:g_attrib_ref() 0x10394f0: g_attrib_ref=1
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:load_gatt_db() Restoring DC:A6:32:07:83:53 gatt database from file
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: No cache for DC:A6:32:07:83:53
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/gatt-client.c:btd_gatt_client_connected() Device connected.
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() MTU exchange complete, with MTU: 517
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() MTU exchange complete, with MTU: 517
Jan 27 19:13:41 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Grp Type - start: 0x0001 end: 0xffff
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Grp Type - start: 0x000a end: 0xffff
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Primary services found: 2
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() start: 0x0001, end: 0x0005, uuid: 00001800-0000-1000-8000-00805f9b34fb
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() start: 0x0006, end: 0x0009, uuid: 00001801-0000-1000-8000-00805f9b34fb
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Grp Type - start: 0x0001 end: 0xffff
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Type - start: 0x0001 end: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read By Type - start: 0x0008 end: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Characteristics found: 3
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() start: 0x0002, end: 0x0003, value: 0x0003, props: 0x02, uuid: 00002a00-0000-1
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() start: 0x0004, end: 0x0006, value: 0x0005, props: 0x02, uuid: 00002a01-0000-1
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() start: 0x0007, end: 0x0009, value: 0x0008, props: 0x20, uuid: 00002a05-0000-1
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Find Info - start: 0x0009 end: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Descriptors found: 1
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() handle: 0x0009, uuid: 00002902-0000-1000-8000-00805f9b34fb
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_client_ready_cb() status: success, error: 0
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:btd_device_set_temporary() temporary 0
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:device_probe_profiles() Probing profiles for device DC:A6:32:07:83:53
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: profiles/gap/gas.c:gap_probe() GAP profile probe (DC:A6:32:07:83:53)
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/service.c:change_state() 0x104bd58: device DC:A6:32:07:83:53 profile gap-profile state changed: unavailable -> disconnected (0)
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: profiles/gap/gas.c:gap_accept() GAP profile accept (DC:A6:32:07:83:53)
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/service.c:change_state() 0x104bd58: device DC:A6:32:07:83:53 profile gap-profile state changed: disconnected -> connected (0)
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:device_probe_profiles() Probing profiles for device DC:A6:32:07:83:53
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-client.c:btd_gatt_client_ready() GATT client ready
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-client.c:create_services() Exporting objects for GATT services: DC:A6:32:07:83:53
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-client.c:service_create() Exported GATT service: /org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-client.c:characteristic_create() Exported GATT characteristic: /org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006/char0007
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-client.c:descriptor_create() Exported GATT characteristic descriptor: /org/bluez/hci0/dev_DC_A6_32_07_83_53/service0006/char0007/desc0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:device_svc_resolved() /org/bluez/hci0/dev_DC_A6_32_07_83_53 err 0
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Write Req - handle: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-database.c:gatt_ccc_write_cb() CCC write called for handle: 0x0009
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read Req - handle: 0x0003
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-database.c:gap_device_name_read_cb() GAP Device Name read request
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Registered handler for "Service Changed": 1
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:gatt_debug() Read Req - handle: 0x0005
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/gatt-database.c:gap_appearance_read_cb() GAP Appearance read request
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: profiles/gap/gas.c:read_device_name_cb() GAP Device Name: raspberrypi
Jan 27 19:13:42 raspberrypi bluetoothd[1537]: src/device.c:btd_device_device_set_name() /org/bluez/hci0/dev_DC_A6_32_07_83_53 raspberrypi
Jan 27 19:13:43 raspberrypi bluetoothd[1537]: profiles/gap/gas.c:read_appearance_cb() GAP Appearance: 0x0000

PS: I have reported a similar issue to the Linux Bluetooth Kernel, but that issue seems to be related to the Ecred flow. The workaround to enable the ecred kernel option moves the process along, but still fails during connection in a few instances. @pelwell

@Path-A
Copy link

Path-A commented Sep 26, 2022

Has anyone on Raspberry Pi team been able to replicate this issue?

@pelwell
Copy link
Member

pelwell commented Sep 27, 2022

Well I haven't - the fact that I don't own a Pixel 6 Pro made it harder to investigate, and the issue was overtaken by others before I got round to borrowing one.

@pelwell
Copy link
Member

pelwell commented Sep 27, 2022

The listed kernel and BlueZ version are both old - we're on Linux 5.15 and BlueZ 5.55. Testing on a OnePlus 7T (running Android 11) with a 4B running current software works as expected.

@pelwell
Copy link
Member

pelwell commented Sep 27, 2022

An S10 running Android 12 also connects to the current Pi 4 software.

@Path-A
Copy link

Path-A commented Oct 5, 2022

Just tested with some newer phones on latest release of Raspbian (Linux 5.15 and BlueZ 5.55). The following phones failed to connect via Bluetooth:

  • Pixel 6, Android 12
  • Pixel 6, Android 13
  • Pixel 6 Pro, Android 13
  • Samsung Ultra 22, Android 12
  • Samsung Ultra 22, Android 13

@cspensky
Copy link

Does anyone have any leads on what might be going on here? I've worked on this quite a bit, and it seems to me like it might be a firmware issue since I have no been able to replicate it on other hardware.

I'm happy to throw some engineering effort toward a fix if there's a solid direction. But, right now I'm still unable to pinpoint the root cause.

@elyssaDev
Copy link

elyssaDev commented Nov 23, 2022

Has there been any word on this? We actively sell a product that uses Raspberry Pi to handle Bluetooth, so we have had multiple customers unable to even get past initial setup because of this bug.

Edit: I don't know if this helps at all, but all of the phones with issues seem to use the exact same BT/Wi-Fi chip: BCM4389

@pelwell
Copy link
Member

pelwell commented Nov 24, 2022

That's potentially useful information, but phone manufacturers don't seem to advertise which chips they use - I've only seen the Broadcom PR around the time of the S21 Ultra launch.

The last time I tried to reproduce this problem using two borrowed phones, one of them didn't show the problem and the other didn't even detect the Pi, but I can ask around for new acquisitions.

@bboozzoo
Copy link

Has anyone tried with Pixel 7 maybe?

@cspensky
Copy link

My Pixel 7 seems to have the same issue 😢

@Piocky
Copy link

Piocky commented Dec 5, 2022

The last time I tried to reproduce this problem using two borrowed phones, one of them didn't show the problem and the other didn't even detect the Pi, but I can ask around for new acquisitions.

Hi @pelwell, do you know if any progress was made on this? We have products on the market with users using an app to pair their device to WiFi, thanks to BLE. If they have an Android phone with the reported issue, the pairing process can't be achieved, which means that they can't use their product.. Thanksfully most of our customers have iPhones, but we can't give up the ones with Androids.. Please let me know if we could help in any way.

@pelwell
Copy link
Member

pelwell commented Dec 5, 2022

Two tested phones - S10, Pixel 6 - connected without issue. I've repeated the test with a new service installation and the S10 - again, no problems - and cast the net wider for test devices.

@Piocky
Copy link

Piocky commented Dec 5, 2022

Didn't you say that on 1 of the 2 phones you borrowed, you weren't able to detect the Pi?

@pelwell
Copy link
Member

pelwell commented Dec 5, 2022

That phone wasn't able to detect anything.

@Path-A
Copy link

Path-A commented Dec 5, 2022

The phones that I have had issues with are from the US. Are you using a phone designed for a different country? I wonder if there might be a different component causing the behavior difference.

@ln-12
Copy link

ln-12 commented Dec 5, 2022

The phones that I have had issues with are from the US. Are you using a phone designed for a different country? I wonder if there might be a different component causing the behavior difference.

I can add that the Pixel 6 which did not work for me was configured for the EU (Germany).

@Piocky
Copy link

Piocky commented Dec 5, 2022

The phones that I have had issues with are from the US. Are you using a phone designed for a different country? I wonder if there might be a different component causing the behavior difference.

I'm in France, and this is the results I had:

  • Galaxy S22 / Android 12: device visible but connection failed
  • Galaxy A12 / Android 11: device visible and connectable
  • Redmi 10 / Android 12: device invisible
  • Pixel 3 / Android 12: device visible and connectable

@Path-A
Copy link

Path-A commented Dec 5, 2022

I'm in France, and this is the results I had:

  • Galaxy S22 / Android 12: device visible but connection failed
  • Galaxy A12 / Android 11: device visible and connectable
  • Redmi 10 / Android 12: device invisible
  • Pixel 3 / Android 12: device visible and connectable

The US version of the Galaxy S22 (unlocked) on Android 12 with newest firmware has been working for our team, so maybe a component can differ.

Our Pixels earlier than the Pixel 5 work fine. Pixel 5 and newer can't connect.

@pelwell
Copy link
Member

pelwell commented Dec 5, 2022

As luck would have it, a US visitor brought their Pixel 7, and for the first time I have a log of a failure to throw at Infineon/Cypress.

@bboozzoo
Copy link

bboozzoo commented Dec 5, 2022

I had some luck with BT firmware from murata-wireless/cyw-bt-patch@bed4497 and bluez 5.65. The device became visible to Pixel 6 and 7 and connectable only after I bumped the firmware version for our chip to one from the commit. Though it's only based on Cypress, so no clue if Infineon provides the exact same binaries.

@pelwell
Copy link
Member

pelwell commented Dec 5, 2022

That commit doesn't appear to change the 43455 firmware. Do you mean the version of BCM4345C0_003.001.025.0187.0366.1MW.hcd that is present when murata-wireless/cyw-bt-patch@bed4497 is the head of tree? That file was introduced in commit murata-wireless/cyw-bt-patch@a5cd83d.

@bboozzoo
Copy link

bboozzoo commented Dec 5, 2022

I took whatever firmware version was in the tree of the commit I mentioned linked to, which was/is the latest master at the time of writing this comment.

@pelwell
Copy link
Member

pelwell commented Dec 5, 2022

I just tried that firmware and it fails the same for the loaner Pixel 7.

@pelwell
Copy link
Member

pelwell commented Dec 6, 2022

Good news - we have a trial firmware from Infineon that allows the Pixel 7 to connect. It will probably take a few days for them to build the relevant change into an official release for us, but the end is in sight.

@Piocky
Copy link

Piocky commented Dec 6, 2022

I just tried a thing suggested by someone in the bluez team, which was rolling back to 1.2-4+rpt2, and it did solve the problem with a Galaxy S22 / Android 12! It was able to connect and exchange info with the CM4, which was impossible before. Any clue what could have possibly change since this version? (had the issue with both 1.2-4+rpt8 and 1.2-4+rpt9).

@Path-A
Copy link

Path-A commented Dec 6, 2022

I just tried a thing suggested by someone in the bluez team, which was rolling back to 1.2-4+rpt2, and it did solve the problem with a Galaxy S22 / Android 12! It was able to connect and exchange info with the CM4, which was impossible before. Any clue what could have possibly change since this version? (had the issue with both 1.2-4+rpt8 and 1.2-4+rpt9).

Could you share the command that you used to downgrade BlueZ to that specific version?

@Piocky
Copy link

Piocky commented Dec 6, 2022

Could you share the command that you used to downgrade BlueZ to that specific version?

Unfortunately I'm not using a command, but Yocto to build an image with a specific version of bluez-firmware 😞

@bboozzoo
Copy link

bboozzoo commented Dec 6, 2022

Could you share the command that you used to downgrade BlueZ to that specific version?

Unfortunately I'm not using a command, but Yocto to build an image with a specific version of bluez-firmware disappointed

Same here. From some experiments, bluez 5.55 (dunfell) worked ok only with some old (like 2+ years old) version of the cypress firmware, but didn't work with the new. I've then tried 5.61 and 5.65 (kirkstone) which worked fine with the new firmware, but did not work with the old one. Also, switching the firmware+bluez I hit a problem with some Android phones defaulting to ATT MTU of 23 bytes (well 27). Before the negotiation would settle on 251 bytes IIRC. I actually suspect that the firmware contains some heuristic to pick the right/most compatible behavior depending on the version of the host contoller (bluez in this case).

@cspensky
Copy link

I did, and the md5sum seems to sync up. I have few things I want to test first. Previously, I had some luck when it set the MTU back down to 20.

@elyssaDev
Copy link

It worked on Pixel 6a (Android 13) and Samsung S21 Ultra (Android 13)!

@cspensky
Copy link

I'm puzzled why I'm not having the same, positive, experience with this firmware...

What versions are y'all running?

Here's what I'm working with on a Raspberry Pi CM4:

$ bluetoothctl --version
bluetoothctl: 5.62

$ cat /etc/os-release
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

And, I'm still seeing this little guy in btmon:

> HCI Event: Disconnect Complete (0x05) plen 4                                                                                    #263 [hci0] 24.233041
        Status: Success (0x00)
        Handle: 64
        Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8                                                                             {0x0001} [hci0] 24.233073
        LE Address: 6D:08:B0:C5:A3:B4 (Resolvable)
        Reason: Connection timeout (0x01)

@elyssaDev
Copy link

Interesting. Do you have any success with the firmware update on a fresh Buster image? I believe that should come with bluez 5.50 instead, which might help.

I know for me at least I've had trouble with newer versions of bluez, especially with NoInputNoOutput pairing, but that's an entirely separate topic.

Anyway, here's what my 3B+ looks like:

$ bluetoothctl --version
bluetoothctl: 5.50

$ cat /etc/os-release
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

@pelwell
Copy link
Member

pelwell commented Dec 14, 2022

@cspensky CM4 and Pi 400 both use the Synaptics 43456, which has different firmware (and a completely separate support system). Can you open a new issue - just a brief note saying that 43456 is also affected - so we can track it separately?

@cspensky
Copy link

@pelwell I'll happily file an issue and try to get that ball rolling (it's actually pretty urgent on our end). However, I'm a bit confused by which company is actual responsible for this chip. Would you be able to provide some insights on where the proper support ticket should be file?

Also, here's what I'm seeing in my dmesg, in case that helps. It is clearly loading BCM4345/6 Jan 4 2021 19:56:29 version 7.45.229.

[    5.023548] brcmfmac: F1 signature read @0x18000000=0x15264345
[    5.042366] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    5.042772] usbcore: registered new interface driver brcmfmac
[    5.058944] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-compute-module.txt failed with error -2
[    5.298315] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    5.308570] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    6.768549] random: crng init done
[    6.768570] random: 7 urandom warning(s) missed due to ratelimiting
[    6.874375] 8021q: 802.1Q VLAN Support v1.8
[    7.077798] uart-pl011 fe201000.serial: no DMA platform data
[    7.213413] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[    7.445975] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    7.734765] Bluetooth: Core ver 2.22

@pelwell
Copy link
Member

pelwell commented Dec 14, 2022

I meant open an issue here - it can link back to this one, but it will allow us to separate the two threads and close them independently.

@pelwell
Copy link
Member

pelwell commented Dec 15, 2022

Infineon have given us an official release containing the fix. After initial testing it will be added to this repo, then added to future releases. In the meantime you can download it from here: https://drive.google.com/file/d/1mgj2sybw7wpSWSMXmlj_0i-z20NS85wd/view?usp=share_link

Full disclosure - it's just arrived, and therefore is currently completely untested by me.

Installation instructions:

$ sudo cp /lib/firmware/brcm/BCM4345C0.hcd BCM4345C0.hcd.backup
$ sudo cp BCM4345C0_003.001.025.0190.0382_RPI_3P.hcd /lib/firmware/brcm/BCM4345C0.hcd

and to revert:

$ sudo cp BCM4345C0.hcd.backup /lib/firmware/brcm/BCM4345C0.hcd

N.B. If you've followed these instructions before for the test firmware, be careful not to overwrite your good backup.

@paulsykes
Copy link

BCM4345C0_003.001.025.0190.0382_RPI_3P.hcd firmware seems to be working okay with my Pixel 6, which was not working previously.

Phone: Pixel 6
Android version: 13
Build number: TP1A.221105.002

@pelwell
Copy link
Member

pelwell commented Dec 15, 2022

Thanks for the feedback!

@cspensky
Copy link

@pelwell any idea if the CM 4 firmware is in the works? I opened a case with Infineon yesterday, but it sounds you already have an active case with them.

@pelwell
Copy link
Member

pelwell commented Dec 15, 2022

I've not had a response from Synaptics on my case yet, but I was able to pass on some more detail about the problem from from Infineon, so hopefully it shouldn't take so long.

@elyssaDev
Copy link

It still works on my Pixel 6a (Android 13) and Samsung S21 Ultra (Android 13)!

pelwell added a commit that referenced this issue Dec 19, 2022
Original rirmware name: BCM4345C0_003.001.025.0190.0382_RPI_3P.hcd

See: #9

Signed-off-by: Phil Elwell <[email protected]>
pull bot pushed a commit to Leuca/bluez-firmware that referenced this issue Dec 19, 2022
Original rirmware name: BCM4345C0_003.001.025.0190.0382_RPI_3P.hcd

See: RPi-Distro#9

Signed-off-by: Phil Elwell <[email protected]>
@macmpi
Copy link

macmpi commented Dec 31, 2022

@pelwell having similar BLE connection issues with some BLE remotes with PiZeroW (original not Zero2): any chance Infineon would apply similar fix to their other parts? Thanks

@pelwell
Copy link
Member

pelwell commented Dec 31, 2022

I'll raise it with them - they should know enough to be able to fix it (or say that it isn't possible).

@pelwell
Copy link
Member

pelwell commented Jan 1, 2023

@macmpi Could you capture a btmon log (text output or the binary file written by sudo btmon -w <name>.bts) covering the failed connection attempt, just in case Infineon ask for one?

@macmpi
Copy link

macmpi commented Jan 1, 2023

Sure. Give me a few days to get hold/rebuild the affected setup (device used for sth else currently).

@pelwell
Copy link
Member

pelwell commented Jan 3, 2023

There's another case open with Infineon. I'll let you know if they demand logs.

@pelwell
Copy link
Member

pelwell commented Jan 4, 2023

I have limited access to a suitable test phone, but trying it now on a Zero W (BCM43438-based) it worked for me.

@memsom
Copy link

memsom commented Jan 20, 2023

So - I'm not anything to do with raspi dev, but I'm an Android App developer for an app that uses BLE to transfer blobs of data from Android to Device and back (bytes from say 10 bytes up to 400 bytes at a time, depending on application.)

The Samsung BLE issues are well documented -

https://forum.developer.samsung.com/t/samsung-android-10-ble-connectivity-regression/509/101
https://stackoverflow.com/questions/61098739/samsung-android-10-ble-mtu23-issue

Here is some info I have gathered. The issue all boils down to MTU, as others have guessed. So - we have a range of test phones. A lot of Pixel 3a's on Android 10 - 12, a few Pixel 6a's, various Samsung S series (S9+, S10, S21), at least one Samsung A series (can't remember the exact model, possibly A30) and a number of S and A series tablets. All of the Samsung devices on Android 10 and above have a very similar issue with MTU - if you set the MTU as something that is not a multiple of 23, they will drop the connection on the first outgoing transfer. The Pixel 3a will support right up to 517. The Samsung devices seem stable above 230 and below 517 (so long as it is a multiple of 23), but I've stuck with 230 as is falls in to the magic multiple of 23 and also is below the UBLOX controller limitation we have for one of the devices. The important key fact here is that we actively request an MTU of the given value, Android accepts the request and then the data we receive is in blocks up to the size we requested, less the BLE header (~3 bytes.) So - under the hood the controller might still be sending data in small blocks, but the transfer is way, way faster than sending 20 byte blocks, so I don't think that is what is happening. The speed the end device thinks is set is the MTU we requested also.

As I alluded to, until recently, I was unable to push the Samsungs above 23 (so ~20 bytes of data, and ~3 bytes of BLE header.) The epiphany happened when I read this article:

https://punchthrough.com/maximizing-ble-throughput-part-2-use-larger-att-mtu-2/

(edit: and this one too https://punchthrough.com/ble-throughput-part-4/ )

Somewhere near the bottom it says "Using ATT_MTU sizes that are multiples of 23 bytes or (Link Layer Data Field – L2CAP Header Size(4 bytes)) is ideal." and that is how I hit on the divisible by 23 realisation. It works - and I got the Samsung devices up to close to 517 using that (~512 I think.)

The Pixel 6a is a different story. A sad one. I can't currently convince it to go above 23. This is what led me here, looking for anyone else with this issue and to see if there was a magic number for the MTU.

In my software I basically need to trawl what the Android phone Manufacturer and Model is and create a blacklist. That probably won't help you guys. If anyone can work out how to convince a Pixel 6a to allow and use a connection with an MTU over 23, I'd definitely eager to hear your solution!

@shawaj
Copy link

shawaj commented Feb 27, 2023

@pelwell is this pulled in to main build now? Or still just the testing firmware from the above comment?

@pelwell
Copy link
Member

pelwell commented Feb 27, 2023

The 43455 update is packaged. The 43456 update was merged later and hasn't yet been packaged. @XECDesign ?

@targettadams
Copy link

targettadams commented Jun 11, 2023

I am seeing issues that are very similar to those reported here. Unfortunately, the issue is arising in a rpi 3b+ which is embedded in a deployed prototype hardware device which I don't have great access to for debugging. Basic question (linux not my forte): if I'm using a raspbian lite install, say 2 months old, is the BCM firmware patch you speak about in this thread likely to be there already, or must I patch it myself per above instructions?

@pelwell
Copy link
Member

pelwell commented Jun 12, 2023

if I'm using a raspbian lite install, say 2 months old, is the BCM firmware patch you speak about in this thread likely to be there already, or must I patch it myself per above instructions?

Yes - you can confirm by running the following and comparing your results with these:

$ strings /lib/firmware/brcm/BCM4345C0.hcd | grep aspb
&BCM43455 37.4MHz Raspberry Pi 3+-0190
$ md5sum /lib/firmware/brcm/BCM4345C0.hcd
36f1b0d5cd62ac4b542b43203d9dc685  /lib/firmware/brcm/BCM4345C0.hcd

@targettadams
Copy link

targettadams commented Jun 12, 2023

Thanks @pelwell. I confirm that I have the patch. Actually, however, having asked the question, I then realised that I have disabled the onboard bluetooth on the pi in question (I use this dongle https://www.amazon.co.uk/dp/B09MZ8715D?psc=1&ref=ppx_yo2ov_dt_b_product_details). The issues I am seeing are suspiciously similar though. Every device I have personally tested works, but then one of the consumers of my hardware (a sports scoreboard) using a pixel 6 pro was seeing repeated disconnects after 5-10 seconds. I'll have to try and get my hands on a pixel 6 pro somehow and debug.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests