RasPi 4 and CM4 Bluetooth issues

shawaj · March 29, 2023, 12:31pm

Have you got any idea when the new Bluetooth firmware for Raspberry Pi 4 / CM4 will be pulled into the balenaOS releases?

Ref the below…

And

github.com/RPi-Distro/bluez-firmware

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

opened 07:20PM - 27 Jan 22 UTC

hando-gomes

A few Android devices are unable to successfully establish a bluetooth connectio…n 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](http://www.bluez.org/study-guide-for-linux-developer/): 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](http://www.bluez.org/study-guide-for-linux-developer/) 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](https://github.com/raspberrypi/linux/issues/4847), 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

mpous · April 5, 2023, 10:30am

Hello @shawaj did you add this feature request on the balena roadmap here? https://roadmap.balena.io/

shawaj · April 8, 2023, 12:03pm

@mpous it’s not really a feature, it’s just an update required to your balenaOS images to pull in the new drivers from upstream.

shawaj · April 16, 2023, 1:12pm

Any news on this @mpous ?

mpous · April 17, 2023, 9:00am

Hello @shawaj no news on this!

Topic		Replies	Views
Bluez issues on Pi 4B on balenaOS balenaOS	1	234	October 20, 2021
Bluetooth Connection Stability Issues balenaOS	2	280	June 22, 2020
Patch Bluez Bluetooth Firmware BCM4345C0.hcd in OS release for RPI? balenaOS bluetooth , raspberrypi4	3	1500	April 29, 2021
impossible to connect in bluetooth on a Rapberry 4 balenaSound bluetooth , raspberrypi4	2	680	September 1, 2021
Balena Dashboard balenaDash	5	461	April 14, 2022

RasPi 4 and CM4 Bluetooth issues

Related topics