Hello,
we are using a Flutter App on iOS and Android.
With iOS the data transfer is as fast as expected, but Android seems to have delay of 2 seconds between the data chunks which are written to one GATT characteristic.
This delay can also be seen in the log of the HCI driver.
[00:08:35.513,702] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
03 00 00 00 cb |.....
[00:08:37.763,793] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
Attached are the logs showing the reception of the same packet in iOS and Android. Using iOS, the log shows no delay between the lines above.
I would be very thankful for any idea how I can further debug this problem.
Many thanks and kind regards,
Chris
[00:01:07.097,381] <dbg> ble: ble_data_received: BLE Data received: Attribute read, handle: 0, conn: 0x20003dc0, flags: 0
[00:01:07.097,412] <dbg> app_main: bleDataReceived: Ble datareceived: len: 50, offset: 0, flags: 0
[00:01:07.097,442] <dbg> app_main: bleDataReceived: Data:
01 31 6f 02 c8 9f 02 00 fc 00 08 08 01 31 6f 02 |.1o..... .....1o.
c9 5f c3 00 fc 00 08 08 01 31 6f 02 ca 5e 83 00 |._...... .1o..^..
fc 00 08 08 01 31 6f 02 cb 9e 42 00 fc 00 08 08 |.....1o. ..B.....
01 31 |.1
[00:01:07.097,473] <dbg> protocol_handler: setResponseWriter: Set Response Writer: 0
[00:01:07.097,503] <dbg> bt_att: chan_send: code 0x13
[00:01:07.097,534] <dbg> bt_l2cap: bt_l2cap_send_cb: conn 0x20003dc0 cid 4 len 1
[00:01:07.097,564] <dbg> bt_conn: bt_conn_send_cb: conn handle 31 buf len 5 cb 0 user_data 0
[00:01:07.097,595] <dbg> bt_conn: bt_conn_ref: handle 31 ref 3 -> 4
[00:01:07.097,625] <dbg> bt_conn: bt_conn_unref: handle 31 ref 4 -> 3
[00:01:07.097,656] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 31
[00:01:07.097,656] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:01:07.097,686] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20042e74
[00:01:07.097,717] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:01:07.097,747] <dbg> bt_hci_core: bt_send: buf 0x20042e74 len 8 type 0
[00:01:07.097,747] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20042e74 type 0 len 8
[00:01:07.097,808] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 1f 00 01 00 |.5...... .
[00:01:07.097,869] <dbg> bt_conn: bt_conn_unref: handle 31 ref 3 -> 2
[00:01:07.097,900] <dbg> bt_hci_core: process_events: count 3
[00:01:07.097,930] <dbg> bt_hci_core: process_events: ev->state 0
[00:01:07.097,930] <dbg> bt_hci_core: process_events: ev->state 0
[00:01:07.097,961] <dbg> bt_hci_core: process_events: ev->state 4
[00:01:07.097,961] <dbg> bt_conn: bt_conn_process_tx: conn 0x20003dc0
[00:01:07.097,991] <dbg> bt_conn: send_buf: conn 0x20003dc0 buf 0x20044eec len 5
[00:01:07.098,022] <dbg> bt_conn: send_buf: send single
[00:01:07.098,052] <dbg> bt_conn: do_send_frag: conn 0x20003dc0 buf 0x20044eec len 5 flags 0x02
[00:01:07.098,052] <dbg> bt_hci_core: bt_send: buf 0x20044eec len 9 type 2
[00:01:07.098,083] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20044eec type 2 len 9
[00:01:07.098,114] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
02 1f 00 05 00 01 00 04 00 13 |........ ..
[00:01:07.098,175] <dbg> bt_att: att_tx_destroy: 0x20044eec
[00:01:07.098,205] <dbg> bt_att: att_on_sent_cb: opcode 0x13
[00:01:07.098,236] <dbg> bt_att: att_on_sent_cb: UATT bearer, calling att_sent
[00:01:07.098,236] <dbg> bt_att: att_sent: conn 0x20003dc0 chan 0x2001d544
[00:01:07.098,266] <dbg> bt_att: bt_att_sent: chan 0x2001d540
[00:01:07.098,297] <dbg> bt_conn: bt_conn_prepare_events:
[00:01:07.098,327] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20003dc0 to poll list
[00:01:07.098,327] <dbg> bt_conn: conn_prepare_events: wait on host fifo
[00:01:07.098,358] <dbg> bt_hci_core: hci_tx_thread: Calling k_poll with 3 events
[00:01:07.152,465] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
04 13 05 01 1f 00 01 00 |........
[00:01:07.152,465] <dbg> bt_hci_driver: bt_ipc_evt_recv: len 5
[00:01:07.152,526] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20042b90)
[00:01:07.152,526] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20042b90 len 7
[00:01:07.152,557] <dbg> bt_hci_core: hci_num_completed_packets: num_handles 1
[00:01:07.152,557] <dbg> bt_hci_core: hci_num_completed_packets: handle 31 count 1
[00:01:07.152,587] <dbg> bt_conn: bt_conn_ref: handle 31 ref 2 -> 3
[00:01:07.152,618] <dbg> bt_conn: bt_conn_unref: handle 31 ref 3 -> 2
[00:01:07.152,648] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
00 19 00 00 8c |.....
[00:01:07.152,862] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 1f 20 1b 00 35 00 04 00 12 12 00 6f 00 98 c3 |.. ..5.. ....o...
03 00 fc 00 08 08 01 31 6f 00 99 03 c2 00 fc 00 |.......1 o.......
[00:01:07.152,893] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 27
[00:01:07.152,923] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20042a00)
[00:01:07.152,923] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20042a00 len 31
[00:01:07.152,954] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:01:07.152,984] <dbg> bt_hci_core: rx_work_handler: buf 0x20042a00 type 3 len 31
[00:01:07.152,984] <dbg> bt_hci_core: hci_acl: buf 0x20042a00
[00:01:07.153,015] <dbg> bt_hci_core: hci_acl: handle 31 len 27 flags 2
[00:01:07.153,015] <dbg> bt_conn: bt_conn_ref: handle 31 ref 2 -> 3
[00:01:07.153,045] <dbg> bt_conn: wait_for_tx_work: conn 0x20003dc0
[00:01:07.153,106] <dbg> bt_conn: tx_notify: conn 0x20003dc0
[00:01:07.153,137] <dbg> bt_conn: wait_for_tx_work: done
[00:01:07.153,137] <dbg> bt_conn: bt_conn_recv: handle 31 len 27 flags 02
[00:01:07.153,167] <dbg> bt_conn: bt_acl_recv: First, len 27 final 53
[00:01:07.153,167] <dbg> bt_conn: bt_conn_unref: handle 31 ref 3 -> 2
[00:01:07.153,350] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
35 00 04 00 12 12 00 6f 00 98 c3 03 00 fc 00 08 |5......o ........
08 01 31 6f 00 99 03 c2 00 fc 00 |..1o.... ...
[00:01:07.153,442] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 1f 10 1b 00 08 08 01 31 6f 00 9a 02 82 00 fc |........ 1o......
00 08 08 01 31 6f 00 9b c2 43 00 fc 00 08 08 01 |....1o.. .C......
[00:01:07.153,533] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 27
[00:01:07.153,564] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20042a1c)
[00:01:07.153,594] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20042a1c len 31
[00:01:07.153,625] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:01:07.153,656] <dbg> bt_hci_core: rx_work_handler: buf 0x20042a1c type 3 len 31
[00:01:07.153,656] <dbg> bt_hci_core: hci_acl: buf 0x20042a1c
[00:01:07.153,656] <dbg> bt_hci_core: hci_acl: handle 31 len 27 flags 1
[00:01:07.153,686] <dbg> bt_conn: bt_conn_ref: handle 31 ref 2 -> 3
[00:01:07.153,717] <dbg> bt_conn: wait_for_tx_work: conn 0x20003dc0
[00:01:07.153,747] <dbg> bt_conn: tx_notify: conn 0x20003dc0
[00:01:07.153,778] <dbg> bt_conn: wait_for_tx_work: done
[00:01:07.153,808] <dbg> bt_conn: bt_conn_recv: handle 31 len 27 flags 01
[00:01:07.153,839] <dbg> bt_conn: bt_conn_ref: handle 31 ref 3 -> 4
[00:01:07.153,869] <dbg> bt_conn: bt_conn_unref: handle 31 ref 4 -> 3
[00:01:07.153,869] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 31
[00:01:07.153,900] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:01:07.153,900] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20042e74
[00:01:07.153,930] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:01:07.153,930] <dbg> bt_hci_core: bt_send: buf 0x20042e74 len 8 type 0
[00:01:07.153,961] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20042e74 type 0 len 8
[00:01:07.153,991] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 1f 00 01 00 |.5...... .
[00:01:07.154,052] <dbg> bt_conn: bt_conn_unref: handle 31 ref 3 -> 2
[00:01:07.154,083] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
9f 2b 05 00 74 2e 04 20 08 00 00 00 00 00 00 00 |.+..t.. ........
00 31 00 00 94 b3 04 00 6f 84 21 |.1...... o.!
[00:01:07.154,144] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 1f 10 03 00 31 6f 00 |.....1o.
[00:01:07.154,174] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 3
[00:01:07.154,205] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20042a1c)
[00:01:07.154,205] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20042a1c len 7
[00:01:07.154,235] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:01:07.154,266] <dbg> bt_hci_core: rx_work_handler: buf 0x20042a1c type 3 len 7
[00:01:07.154,296] <dbg> bt_hci_core: hci_acl: buf 0x20042a1c
[00:01:07.154,296] <dbg> bt_hci_core: hci_acl: handle 31 len 3 flags 1
[00:01:07.154,327] <dbg> bt_conn: bt_conn_ref: handle 31 ref 2 -> 3
[00:01:07.154,357] <dbg> bt_conn: wait_for_tx_work: conn 0x20003dc0
[00:01:07.154,388] <dbg> bt_conn: tx_notify: conn 0x20003dc0
[00:01:07.154,449] <dbg> bt_conn: wait_for_tx_work: done
[00:01:07.154,479] <dbg> bt_conn: bt_conn_recv: handle 31 len 3 flags 01
[00:01:07.154,510] <dbg> bt_conn: bt_conn_ref: handle 31 ref 3 -> 4
[00:01:07.154,510] <dbg> bt_conn: bt_conn_unref: handle 31 ref 4 -> 3
[00:01:07.154,541] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 31
[00:01:07.154,571] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:01:07.154,602] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20042e74
[00:01:07.154,602] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:01:07.154,632] <dbg> bt_hci_core: bt_send: buf 0x20042e74 len 8 type 0
[00:01:07.154,663] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20042e74 type 0 len 8
[00:01:07.154,693] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 1f 00 01 00 |.5...... .
[00:01:07.154,754] <dbg> bt_conn: bt_acl_recv: Successfully parsed 57 byte L2CAP packet
[00:01:07.154,785] <dbg> bt_l2cap: bt_l2cap_recv: Packet for CID 4 len 53
[00:01:07.154,785] <dbg> bt_l2cap: l2cap_chan_recv: chan 0x2001d544 len 53
[00:01:07.154,815] <dbg> bt_att: bt_att_recv: Received ATT chan 0x2001d540 code 0x12 len 52
[00:01:07.154,846] <dbg> bt_att: att_write_req: handle 0x0012
[00:01:07.154,907] <dbg> bt_att: write_cb: handle 0x0012 offset 0
[00:08:35.493,194] <dbg> ble: ble_data_received: BLE Data received: Attribute read, handle: 0, conn: 0x20002dc0, flags: 0
[00:08:35.493,194] <dbg> app_main: bleDataReceived: Ble datareceived: len: 50, offset: 0, flags: 0
[00:08:35.493,225] <dbg> app_main: bleDataReceived: Data:
01 31 6f 02 c8 9f 02 00 fc 00 08 08 01 31 6f 02 |.1o..... .....1o.
c9 5f c3 00 fc 00 08 08 01 31 6f 02 ca 5e 83 00 |._...... .1o..^..
fc 00 08 08 01 31 6f 02 cb 9e 42 00 fc 00 08 08 |.....1o. ..B.....
01 31 |.1
[00:08:35.493,255] <dbg> protocol_handler: setResponseWriter: Set Response Writer: 0
[00:08:35.493,286] <dbg> bt_att: chan_send: code 0x13
[00:08:35.493,286] <dbg> bt_l2cap: bt_l2cap_send_cb: conn 0x20002dc0 cid 4 len 1
[00:08:35.493,316] <dbg> bt_conn: bt_conn_send_cb: conn handle 79 buf len 5 cb 0 user_data 0
[00:08:35.493,347] <dbg> bt_conn: bt_conn_ref: handle 79 ref 3 -> 4
[00:08:35.493,377] <dbg> bt_conn: bt_conn_unref: handle 79 ref 4 -> 3
[00:08:35.493,377] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 79
[00:08:35.493,408] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:08:35.493,438] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20041e74
[00:08:35.493,438] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:08:35.493,469] <dbg> bt_hci_core: bt_send: buf 0x20041e74 len 8 type 0
[00:08:35.493,469] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20041e74 type 0 len 8
[00:08:35.493,499] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 4f 00 01 00 |.5...O.. .
[00:08:35.493,560] <dbg> bt_conn: bt_conn_unref: handle 79 ref 3 -> 2
[00:08:35.493,591] <dbg> bt_hci_core: process_events: count 3
[00:08:35.493,621] <dbg> bt_hci_core: process_events: ev->state 0
[00:08:35.493,621] <dbg> bt_hci_core: process_events: ev->state 0
[00:08:35.493,621] <dbg> bt_hci_core: process_events: ev->state 4
[00:08:35.493,652] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002dc0
[00:08:35.493,652] <dbg> bt_conn: send_buf: conn 0x20002dc0 buf 0x20043ecc len 5
[00:08:35.493,682] <dbg> bt_conn: send_buf: send single
[00:08:35.493,713] <dbg> bt_conn: do_send_frag: conn 0x20002dc0 buf 0x20043ecc len 5 flags 0x02
[00:08:35.493,713] <dbg> bt_hci_core: bt_send: buf 0x20043ecc len 9 type 2
[00:08:35.493,743] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20043ecc type 2 len 9
[00:08:35.493,743] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
02 4f 00 05 00 01 00 04 00 13 |.O...... ..
[00:08:35.493,804] <dbg> bt_att: att_tx_destroy: 0x20043ecc
[00:08:35.493,835] <dbg> bt_att: att_on_sent_cb: opcode 0x13
[00:08:35.493,835] <dbg> bt_att: att_on_sent_cb: UATT bearer, calling att_sent
[00:08:35.493,865] <dbg> bt_att: att_sent: conn 0x20002dc0 chan 0x2001c544
[00:08:35.493,865] <dbg> bt_att: bt_att_sent: chan 0x2001c540
[00:08:35.493,896] <dbg> bt_conn: bt_conn_prepare_events:
[00:08:35.493,927] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002dc0 to poll list
[00:08:35.493,927] <dbg> bt_conn: conn_prepare_events: wait on host fifo
[00:08:35.493,957] <dbg> bt_hci_core: hci_tx_thread: Calling k_poll with 3 events
[00:08:35.494,171] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
00 21 00 |.!.
[00:08:35.513,519] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
04 13 05 01 4f 00 01 00 |....O...
[00:08:35.513,549] <dbg> bt_hci_driver: bt_ipc_evt_recv: len 5
[00:08:35.513,580] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20041b90)
[00:08:35.513,610] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20041b90 len 7
[00:08:35.513,610] <dbg> bt_hci_core: hci_num_completed_packets: num_handles 1
[00:08:35.513,641] <dbg> bt_hci_core: hci_num_completed_packets: handle 79 count 1
[00:08:35.513,641] <dbg> bt_conn: bt_conn_ref: handle 79 ref 2 -> 3
[00:08:35.513,671] <dbg> bt_conn: bt_conn_unref: handle 79 ref 3 -> 2
[00:08:35.513,702] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
03 00 00 00 cb |.....
[00:08:37.763,793] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 4f 20 1b 00 35 00 04 00 12 12 00 6f 00 98 c3 |.O ..5.. ....o...
03 00 fc 00 08 08 01 31 6f 00 99 03 c2 00 fc 00 |.......1 o.......
[00:08:37.763,824] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 27
[00:08:37.763,854] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20041a00)
[00:08:37.763,854] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20041a00 len 31
[00:08:37.763,916] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:08:37.763,916] <dbg> bt_hci_core: rx_work_handler: buf 0x20041a00 type 3 len 31
[00:08:37.763,946] <dbg> bt_hci_core: hci_acl: buf 0x20041a00
[00:08:37.763,946] <dbg> bt_hci_core: hci_acl: handle 79 len 27 flags 2
[00:08:37.763,977] <dbg> bt_conn: bt_conn_ref: handle 79 ref 2 -> 3
[00:08:37.763,977] <dbg> bt_conn: wait_for_tx_work: conn 0x20002dc0
[00:08:37.764,038] <dbg> bt_conn: tx_notify: conn 0x20002dc0
[00:08:37.764,099] <dbg> bt_conn: wait_for_tx_work: done
[00:08:37.764,099] <dbg> bt_conn: bt_conn_recv: handle 79 len 27 flags 02
[00:08:37.764,129] <dbg> bt_conn: bt_acl_recv: First, len 27 final 53
[00:08:37.764,129] <dbg> bt_conn: bt_conn_unref: handle 79 ref 3 -> 2
[00:08:37.764,190] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
35 00 04 00 12 12 00 6f 00 98 c3 03 00 fc 00 08 |5......o ........
08 01 31 6f 00 99 03 c2 00 fc 00 |..1o.... ...
[00:08:37.775,146] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 4f 10 1b 00 08 08 01 31 6f 00 9a 02 82 00 fc |.O...... 1o......
00 08 08 01 31 6f 00 9b c2 43 00 fc 00 08 08 01 |....1o.. .C......
[00:08:37.775,177] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 27
[00:08:37.775,207] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20041a1c)
[00:08:37.775,207] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20041a1c len 31
[00:08:37.775,268] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:08:37.775,268] <dbg> bt_hci_core: rx_work_handler: buf 0x20041a1c type 3 len 31
[00:08:37.775,299] <dbg> bt_hci_core: hci_acl: buf 0x20041a1c
[00:08:37.775,299] <dbg> bt_hci_core: hci_acl: handle 79 len 27 flags 1
[00:08:37.775,329] <dbg> bt_conn: bt_conn_ref: handle 79 ref 2 -> 3
[00:08:37.775,360] <dbg> bt_conn: wait_for_tx_work: conn 0x20002dc0
[00:08:37.775,390] <dbg> bt_conn: tx_notify: conn 0x20002dc0
[00:08:37.775,421] <dbg> bt_conn: wait_for_tx_work: done
[00:08:37.775,451] <dbg> bt_conn: bt_conn_recv: handle 79 len 27 flags 01
[00:08:37.775,482] <dbg> bt_conn: bt_conn_ref: handle 79 ref 3 -> 4
[00:08:37.775,512] <dbg> bt_conn: bt_conn_unref: handle 79 ref 4 -> 3
[00:08:37.775,512] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 79
[00:08:37.775,543] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:08:37.775,543] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20041e74
[00:08:37.775,573] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:08:37.775,573] <dbg> bt_hci_core: bt_send: buf 0x20041e74 len 8 type 0
[00:08:37.775,604] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20041e74 type 0 len 8
[00:08:37.775,634] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 4f 00 01 00 |.5...O.. .
[00:08:37.775,695] <dbg> bt_conn: bt_conn_unref: handle 79 ref 3 -> 2
[00:08:37.775,726] <dbg> bt_hci_driver: bt_ipc_rx: RX buf payload:
14 bf 01 01 74 1e 04 20 03 00 00 00 cb 32 05 00 |....t.. .....2..
7c 39 05 00 94 b3 04 00 00 31 00 ||9...... .1.
[00:08:38.900,024] <dbg> bt_hci_driver: bt_ipc_rx: ipc data:
02 4f 10 03 00 31 6f 00 |.O...1o.
[00:08:38.900,054] <dbg> bt_hci_driver: bt_ipc_acl_recv: len 3
[00:08:38.900,085] <dbg> bt_hci_driver: bt_ipc_rx: Calling bt_recv(0x20041a1c)
[00:08:38.900,085] <dbg> bt_hci_core: bt_recv_unsafe: buf 0x20041a1c len 7
[00:08:38.900,146] <dbg> bt_hci_core: rx_work_handler: Getting net_buf from queue
[00:08:38.900,146] <dbg> bt_hci_core: rx_work_handler: buf 0x20041a1c type 3 len 7
[00:08:38.900,177] <dbg> bt_hci_core: hci_acl: buf 0x20041a1c
[00:08:38.900,177] <dbg> bt_hci_core: hci_acl: handle 79 len 3 flags 1
[00:08:38.900,207] <dbg> bt_conn: bt_conn_ref: handle 79 ref 2 -> 3
[00:08:38.900,207] <dbg> bt_conn: wait_for_tx_work: conn 0x20002dc0
[00:08:38.900,268] <dbg> bt_conn: tx_notify: conn 0x20002dc0
[00:08:38.900,329] <dbg> bt_conn: wait_for_tx_work: done
[00:08:38.900,329] <dbg> bt_conn: bt_conn_recv: handle 79 len 3 flags 01
[00:08:38.900,360] <dbg> bt_conn: bt_conn_ref: handle 79 ref 3 -> 4
[00:08:38.900,390] <dbg> bt_conn: bt_conn_unref: handle 79 ref 4 -> 3
[00:08:38.900,390] <dbg> bt_hci_core: bt_hci_host_num_completed_packets: Reporting completed packet for handle 79
[00:08:38.900,421] <dbg> bt_hci_core: bt_hci_cmd_create: opcode 0x0c35 param_len 5
[00:08:38.900,421] <dbg> bt_hci_core: bt_hci_cmd_create: buf 0x20041e74
[00:08:38.900,451] <dbg> bt_hci_core: bt_hci_cmd_send: opcode 0x0c35 len 8
[00:08:38.900,451] <dbg> bt_hci_core: bt_send: buf 0x20041e74 len 8 type 0
[00:08:38.900,482] <dbg> bt_hci_driver: bt_ipc_send: buf 0x20041e74 type 0 len 8
[00:08:38.900,512] <dbg> bt_hci_driver: bt_ipc_send: Final HCI buffer:
01 35 0c 05 01 4f 00 01 00 |.5...O.. .
[00:08:38.900,573] <dbg> bt_conn: bt_acl_recv: Successfully parsed 57 byte L2CAP packet
[00:08:38.900,573] <dbg> bt_l2cap: bt_l2cap_recv: Packet for CID 4 len 53
[00:08:38.900,604] <dbg> bt_l2cap: l2cap_chan_recv: chan 0x2001c544 len 53
[00:08:38.900,604] <dbg> bt_att: bt_att_recv: Received ATT chan 0x2001c540 code 0x12 len 52
[00:08:38.900,634] <dbg> bt_att: att_write_req: handle 0x0012
[00:08:38.900,665] <dbg> bt_att: write_cb: handle 0x0012 offset 0
