Problem bl_conn_disconnect NRF52 - disconnection

Hi y'all.

I've been developing in Zephyr NRF52840. My code is based in Central_HR_Coded (SDK 2.6.1, Toolchain 2.7.0). I had some issues with the bt_conn_disconnect function. This function is being called after a command in the uart stack that triggers this function in this way:

ISR UART -> Work Queue (with k_work_summit) -> callback of disconnection request:

static void disconnection_request(void)
{
	if (curr_connection) {
        int err = bt_conn_disconnect(curr_connection, BT_HCI_ERR_REMOTE_USER_TERM_CONN);
        if (err) {
            LOG_ERR("Disconnection failed (err %d)", err);
			send_err_data(FRONTEND_ERR_DISCONNECTION, err);
        } else {
            LOG_INF("Disconnection requested");
        }
    } else {
        LOG_INF("No active connection to disconnect");
    }
}

curr_connection variable is define using bt_conn_le_create. The issue is I'm getting Error -128 in the bt_conn_disconnect function, which means literally ENOTCONN /* Socket is not connected */.

This is happening because curr_connection.state property is retrieving BT_CONN_DISCONNECTED, but i can assure that both devices are connected due to notifications in a service. I already discard the fact I'm possibly using this function in a interrupt routine. (105524/bt_conn_disconnect-got-errors).

I want to know why this error is popping and also how can i workaround it. What gives?

Parents
  • Hi

    Before this "disconnection failed" error, there is also a code reported in the "Disconnecting:" log  message that says (0x86). Is this an error code reported by a function in your application as well? In that case it means "-ENOTSUPP" or an "unsupported value", in which case it depends on what function is reporting this, and what parameters are input there.

    It's also strange that it is the Disconnection that seemingly fails here, probably because it has already been disconnected and tries to disconnect again for some reason.

    Best regards,

    Simon

Reply
  • Hi

    Before this "disconnection failed" error, there is also a code reported in the "Disconnecting:" log  message that says (0x86). Is this an error code reported by a function in your application as well? In that case it means "-ENOTSUPP" or an "unsupported value", in which case it depends on what function is reporting this, and what parameters are input there.

    It's also strange that it is the Disconnection that seemingly fails here, probably because it has already been disconnected and tries to disconnect again for some reason.

    Best regards,

    Simon

Children
  • Hi . Indeed, the "Disconennecting: ..." log message is an appplication message from us. This is the current code:

    static void disconnection_request(void)
    {
      if (curr_connection) {
        char addr[BT_ADDR_LE_STR_LEN];
        // Print address
        bt_addr_le_to_str(bt_conn_get_dst(curr_connection), addr, sizeof(addr));
        LOG_WRN("Disconnecting ...%s", addr);
        
        // Try to disconnect
        int err = bt_conn_disconnect(curr_connection, BT_HCI_ERR_REMOTE_USER_TERM_CONN);
        if (err) {
            LOG_ERR("Disconnection failed (err %d)", err);
            send_err_data(FRONTEND_ERR_DISCONNECTION, err);
        } else {
            LOG_INF("Disconnection requested");
        }
        } else {
            LOG_INF("No active connection to disconnect");
        }
    }

    The problem we found is that the address don't match with the actual connected node. The correct MAC should be C1:F6:44:29:64:86, but we get somehow as you can see 01:C1:F6:44:29:64 (type 0x86) like it was shifted. This LOG is obtain by using the function bt_addr_le_to_str(). Also i'll going to share some deeper log info. The Central after the connection subscribes to 4 chars from a custom service. After this is going to be notified by any change of those variables. Hope this helps in your understanding.

    Regards!

    LOG INFO FROM CONNECTION STATUS
    
    [00:01:10.612,640] <inf> main_log: rx_uart_callback: Getting data from header  1, len: 7
    [00:01:10.612,792] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:10.612,915] <dbg> bt_conn: bt_conn_set_state: disconnected -> connecting
    [00:01:10.612,945] <dbg> bt_conn: bt_conn_ref: handle 0 ref 1 -> 2
    [00:01:10.613,067] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:10.613,403] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:10.613,433] <inf> main_log: connection_request: Connection pending
    [00:01:11.660,369] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.660,522] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.660,583] <dbg> bt_conn: bt_conn_set_state: connecting -> connected
    [00:01:11.661,529] <inf> main_log: connected: Connected: C1:F6:44:29:64:86 (random), tx_phy 4, rx_phy 4
    [00:01:11.661,590] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 27 cb 0x30eb9 user_data 0x20003bec
    [00:01:11.661,743] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.661,743] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:11.661,743] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:11.661,773] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:11.661,804] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 27
    [00:01:11.661,804] <dbg> bt_conn: send_buf: send single
    [00:01:11.661,834] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 27 flags 0x02
    [00:01:11.661,865] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.661,895] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:11.661,926] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:11.662,048] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.662,078] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.662,078] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.662,109] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.662,109] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.665,649] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.665,679] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.665,710] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:11.665,740] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.665,771] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:11.765,106] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.765,136] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.765,136] <dbg> bt_conn: bt_conn_recv: handle 0 len 9 flags 02
    [00:01:11.765,167] <dbg> bt_conn: bt_acl_recv: First, len 9 final 5
    [00:01:11.765,167] <dbg> bt_conn: bt_acl_recv: Successfully parsed 9 byte L2CAP packet
    [00:01:11.765,289] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c00
    [00:01:11.765,319] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.765,350] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:11.765,380] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:11.765,380] <dbg> bt_conn: send_buf: send single
    [00:01:11.765,411] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:11.765,441] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.765,472] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:11.765,472] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:11.815,063] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.815,093] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.815,216] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.815,246] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.815,277] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:11.815,277] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.815,307] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:11.865,173] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.865,203] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.865,203] <dbg> bt_conn: bt_conn_recv: handle 0 len 10 flags 02
    [00:01:11.865,234] <dbg> bt_conn: bt_acl_recv: First, len 10 final 6
    [00:01:11.865,234] <dbg> bt_conn: bt_acl_recv: Successfully parsed 10 byte L2CAP packet
    [00:01:11.865,325] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c14
    [00:01:11.865,356] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.865,386] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:11.865,417] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:11.865,417] <dbg> bt_conn: send_buf: send single
    [00:01:11.865,447] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:11.865,478] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.865,478] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:11.865,509] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:11.915,069] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.915,130] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.915,222] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.915,252] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.915,283] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:11.915,283] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.915,313] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:11.965,484] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:11.965,515] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:11.965,515] <dbg> bt_conn: bt_conn_recv: handle 0 len 24 flags 02
    [00:01:11.965,545] <dbg> bt_conn: bt_acl_recv: First, len 24 final 20
    [00:01:11.965,576] <dbg> bt_conn: bt_acl_recv: Successfully parsed 24 byte L2CAP packet
    [00:01:11.965,637] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003bec
    [00:01:11.965,698] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:11.965,698] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:11.965,728] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:11.965,728] <dbg> bt_conn: send_buf: send single
    [00:01:11.965,759] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:11.965,789] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:11.965,820] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:11.965,820] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.014,495] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.014,526] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.014,587] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.014,617] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.014,617] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:12.064,849] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.064,880] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.064,880] <dbg> bt_conn: bt_conn_recv: handle 0 len 14 flags 02
    [00:01:12.064,910] <dbg> bt_conn: bt_acl_recv: First, len 14 final 10
    [00:01:12.064,910] <dbg> bt_conn: bt_acl_recv: Successfully parsed 14 byte L2CAP packet
    [00:01:12.065,002] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c00
    [00:01:12.065,032] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.065,063] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.065,093] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.065,093] <dbg> bt_conn: send_buf: send single
    [00:01:12.065,124] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.065,155] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.065,185] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.065,185] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.114,501] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.114,532] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.114,593] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.114,593] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.114,624] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:12.215,515] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.215,515] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.215,545] <dbg> bt_conn: bt_conn_recv: handle 0 len 24 flags 02
    [00:01:12.215,545] <dbg> bt_conn: bt_acl_recv: First, len 24 final 20
    [00:01:12.215,545] <dbg> bt_conn: bt_acl_recv: Successfully parsed 24 byte L2CAP packet
    [00:01:12.215,637] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c14
    [00:01:12.215,698] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.215,698] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.215,728] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.215,728] <dbg> bt_conn: send_buf: send single
    [00:01:12.215,759] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.215,789] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.215,820] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.215,820] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.264,495] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.264,526] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.264,587] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.264,617] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.264,617] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:12.314,849] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.314,880] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.314,880] <dbg> bt_conn: bt_conn_recv: handle 0 len 14 flags 02
    [00:01:12.314,910] <dbg> bt_conn: bt_acl_recv: First, len 14 final 10
    [00:01:12.314,910] <dbg> bt_conn: bt_acl_recv: Successfully parsed 14 byte L2CAP packet
    [00:01:12.315,002] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003bec
    [00:01:12.315,063] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.315,063] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.315,093] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.315,093] <dbg> bt_conn: send_buf: send single
    [00:01:12.315,124] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.315,155] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.315,185] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.315,185] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.364,501] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.364,532] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.364,593] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.364,593] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.364,624] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:12.465,484] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.465,515] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.465,515] <dbg> bt_conn: bt_conn_recv: handle 0 len 24 flags 02
    [00:01:12.465,545] <dbg> bt_conn: bt_acl_recv: First, len 24 final 20
    [00:01:12.465,545] <dbg> bt_conn: bt_acl_recv: Successfully parsed 24 byte L2CAP packet
    [00:01:12.465,667] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c00
    [00:01:12.465,698] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.465,728] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.465,759] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.465,759] <dbg> bt_conn: send_buf: send single
    [00:01:12.465,789] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.465,820] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.465,850] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.465,850] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.514,495] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.514,526] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.514,587] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.514,617] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.514,617] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:12.614,868] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.614,868] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.614,898] <dbg> bt_conn: bt_conn_recv: handle 0 len 14 flags 02
    [00:01:12.614,898] <dbg> bt_conn: bt_acl_recv: First, len 14 final 10
    [00:01:12.614,929] <dbg> bt_conn: bt_acl_recv: Successfully parsed 14 byte L2CAP packet
    [00:01:12.614,990] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c14
    [00:01:12.615,051] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.615,081] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.615,081] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.615,112] <dbg> bt_conn: send_buf: send single
    [00:01:12.615,112] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.615,173] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.615,173] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.615,173] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.714,385] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.714,447] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.714,508] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.714,508] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.714,508] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:12.717,803] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.717,834] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.717,834] <dbg> bt_conn: bt_conn_recv: handle 0 len 24 flags 02
    [00:01:12.717,864] <dbg> bt_conn: bt_acl_recv: First, len 24 final 20
    [00:01:12.717,864] <dbg> bt_conn: bt_acl_recv: Successfully parsed 24 byte L2CAP packet
    [00:01:12.717,956] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003bec
    [00:01:12.717,987] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.718,017] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.718,048] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:12.718,048] <dbg> bt_conn: send_buf: send single
    [00:01:12.718,078] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:12.718,109] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.718,139] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.718,139] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.764,495] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.764,526] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.764,587] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.764,617] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.764,617] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:12.814,605] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.814,605] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.814,636] <dbg> bt_conn: bt_conn_recv: handle 0 len 10 flags 02
    [00:01:12.814,636] <dbg> bt_conn: bt_acl_recv: First, len 10 final 6
    [00:01:12.814,666] <dbg> bt_conn: bt_acl_recv: Successfully parsed 10 byte L2CAP packet
    [00:01:12.814,727] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 11 cb 0x30eb9 user_data 0x20003c00
    [00:01:12.814,788] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.814,819] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.814,819] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 11
    [00:01:12.814,849] <dbg> bt_conn: send_buf: send single
    [00:01:12.814,849] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 11 flags 0x02
    [00:01:12.814,910] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.814,910] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.814,941] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.864,624] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.864,654] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.864,715] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.864,746] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.864,746] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:12.915,679] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.915,710] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.915,710] <dbg> bt_conn: bt_conn_recv: handle 0 len 27 flags 02
    [00:01:12.915,740] <dbg> bt_conn: bt_acl_recv: First, len 27 final 23
    [00:01:12.915,740] <dbg> bt_conn: bt_acl_recv: Successfully parsed 27 byte L2CAP packet
    [00:01:12.915,832] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 11 cb 0x30eb9 user_data 0x20003c14
    [00:01:12.915,863] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.915,893] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:12.915,924] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 11
    [00:01:12.915,924] <dbg> bt_conn: send_buf: send single
    [00:01:12.915,954] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 11 flags 0x02
    [00:01:12.915,985] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:12.916,015] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:12.916,015] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:12.964,630] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:12.964,660] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:12.964,721] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:12.964,721] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:12.964,752] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:13.015,686] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.015,716] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.015,716] <dbg> bt_conn: bt_conn_recv: handle 0 len 27 flags 02
    [00:01:13.015,747] <dbg> bt_conn: bt_acl_recv: First, len 27 final 23
    [00:01:13.015,747] <dbg> bt_conn: bt_acl_recv: Successfully parsed 27 byte L2CAP packet
    [00:01:13.015,838] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 11 cb 0x30eb9 user_data 0x20003bec
    [00:01:13.015,869] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.015,899] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.015,930] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 11
    [00:01:13.015,930] <dbg> bt_conn: send_buf: send single
    [00:01:13.015,960] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 11 flags 0x02
    [00:01:13.015,991] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.015,991] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.016,021] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.064,605] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.064,666] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.064,727] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.064,727] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.064,758] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:13.115,692] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.115,692] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.115,722] <dbg> bt_conn: bt_conn_recv: handle 0 len 27 flags 02
    [00:01:13.115,722] <dbg> bt_conn: bt_acl_recv: First, len 27 final 23
    [00:01:13.115,753] <dbg> bt_conn: bt_acl_recv: Successfully parsed 27 byte L2CAP packet
    [00:01:13.115,844] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 11 cb 0x30eb9 user_data 0x20003c00
    [00:01:13.115,875] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    
    Julio Cortes, [3/31/25 3:47 PM]
    
    [00:01:13.115,905] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.115,905] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 11
    [00:01:13.115,936] <dbg> bt_conn: send_buf: send single
    [00:01:13.115,936] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 11 flags 0x02
    [00:01:13.115,997] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.115,997] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.116,027] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.164,611] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.164,672] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.164,703] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.164,733] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.164,733] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:13.215,698] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.215,698] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.215,728] <dbg> bt_conn: bt_conn_recv: handle 0 len 27 flags 02
    [00:01:13.215,728] <dbg> bt_conn: bt_acl_recv: First, len 27 final 23
    [00:01:13.215,759] <dbg> bt_conn: bt_acl_recv: Successfully parsed 27 byte L2CAP packet
    [00:01:13.215,850] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 11 cb 0x30eb9 user_data 0x20003c14
    [00:01:13.215,911] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.215,942] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.215,942] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 11
    [00:01:13.215,972] <dbg> bt_conn: send_buf: send single
    [00:01:13.215,972] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 11 flags 0x02
    [00:01:13.216,003] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.216,033] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.216,033] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.264,617] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.264,678] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.264,709] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.264,739] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.264,739] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:13.364,532] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.364,562] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.364,562] <dbg> bt_conn: bt_conn_recv: handle 0 len 9 flags 02
    [00:01:13.364,593] <dbg> bt_conn: bt_acl_recv: First, len 9 final 5
    [00:01:13.364,593] <dbg> bt_conn: bt_acl_recv: Successfully parsed 9 byte L2CAP packet
    [00:01:13.364,624] <inf> main_log: discover_smtp_completed: The discovery procedure succeeded
    [00:01:13.364,654] <inf> smtp_client_log: bt_smtp_client_handles_assign: Getting handles from SMTP service.
    [00:01:13.364,685] <inf> smtp_client_log: bt_smtp_client_handles_assign: SMTP Strain Gauge and CCC characteristic found
    [00:01:13.364,746] <inf> smtp_client_log: bt_smtp_client_handles_assign: SMTP Temperature and CCC characteristic found
    [00:01:13.364,776] <inf> smtp_client_log: bt_smtp_client_handles_assign: Config State characteristic found
    [00:01:13.364,837] <inf> smtp_client_log: bt_smtp_client_handles_assign: SMTP Battery Level and CCC characteristic found
    [00:01:13.364,898] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 7 cb 0x30eb9 user_data 0x20003bec
    [00:01:13.364,959] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.364,990] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.365,020] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 7
    [00:01:13.365,020] <dbg> bt_conn: send_buf: send single
    [00:01:13.365,051] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 7 flags 0x02
    [00:01:13.365,081] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.365,112] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.365,112] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    
    Julio Cortes, [3/31/25 3:47 PM]
    
    [00:01:13.365,203] <inf> smtp_client_log: bt_smtp_client_strain_gauge_subscribe: [SUBSCRIBED] to SMTP Strain Gauge characteristic
    [00:01:13.365,234] <inf> smtp_client_log: bt_smtp_client_temperature_subscribe: [SUBSCRIBED] to SMTP Temperature characteristic
    [00:01:13.414,367] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.414,398] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.464,355] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.464,355] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.464,385] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:13.464,416] <dbg> bt_conn: bt_conn_recv: handle 0 len 6 flags 02
    [00:01:13.464,416] <dbg> bt_conn: bt_acl_recv: First, len 6 final 2
    [00:01:13.464,447] <dbg> bt_conn: bt_acl_recv: Successfully parsed 6 byte L2CAP packet
    [00:01:13.464,477] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c00
    [00:01:13.464,508] <inf> main_log: notify_func_state: Previous State: 0, Current State: 0
    [00:01:13.464,813] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.464,843] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.464,843] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:13.464,843] <dbg> bt_conn: send_buf: send single
    [00:01:13.464,874] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:13.464,904] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.464,935] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.464,935] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.465,026] <inf> smtp_client_log: bt_smtp_client_config_state_subscribe: [SUBSCRIBED] to SMTP Config State characteristic
    [00:01:13.514,495] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.514,526] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.564,270] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.564,300] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.564,300] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:13.564,361] <dbg> bt_conn: bt_conn_recv: handle 0 len 5 flags 02
    [00:01:13.564,361] <dbg> bt_conn: bt_acl_recv: First, len 5 final 1
    [00:01:13.564,361] <dbg> bt_conn: bt_acl_recv: Successfully parsed 5 byte L2CAP packet
    [00:01:13.564,422] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c14
    [00:01:13.564,453] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.564,483] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.564,514] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a118 len 9
    [00:01:13.564,514] <dbg> bt_conn: send_buf: send single
    [00:01:13.564,544] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a118 len 9 flags 0x02
    [00:01:13.564,575] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.564,605] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.564,605] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.564,666] <inf> smtp_client_log: bt_smtp_client_batt_level_subscribe: [SUBSCRIBED] to SMTP Battery Level characteristic
    [00:01:13.564,697] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.564,697] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.614,501] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.614,532] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.614,593] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.614,593] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.614,624] <dbg> bt_conn: tx_notify: tx 0x20003b9c cb 0x30eb9 user_data 0x20003c14
    [00:01:13.664,276] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.664,306] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.664,306] <dbg> bt_conn: bt_conn_recv: handle 0 len 5 flags 02
    [00:01:13.664,337] <dbg> bt_conn: bt_acl_recv: First, len 5 final 1
    [00:01:13.664,337] <dbg> bt_conn: bt_acl_recv: Successfully parsed 5 byte L2CAP packet
    
    Julio Cortes, [3/31/25 3:47 PM]
    
    [00:01:13.664,367] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003bec
    [00:01:13.664,428] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.664,459] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.664,459] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a0f8 len 9
    [00:01:13.664,489] <dbg> bt_conn: send_buf: send single
    [00:01:13.664,489] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a0f8 len 9 flags 0x02
    [00:01:13.664,550] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.664,550] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.664,581] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.714,477] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.714,538] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.714,599] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.714,599] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.714,630] <dbg> bt_conn: tx_notify: tx 0x20003b7c cb 0x30eb9 user_data 0x20003bec
    [00:01:13.814,270] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.814,300] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.814,300] <dbg> bt_conn: bt_conn_recv: handle 0 len 5 flags 02
    [00:01:13.814,331] <dbg> bt_conn: bt_acl_recv: First, len 5 final 1
    [00:01:13.814,331] <dbg> bt_conn: bt_acl_recv: Successfully parsed 5 byte L2CAP packet
    [00:01:13.814,392] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 9 cb 0x30eb9 user_data 0x20003c00
    [00:01:13.814,422] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.814,453] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:13.814,453] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a118 len 9
    [00:01:13.814,483] <dbg> bt_conn: send_buf: send single
    [00:01:13.814,483] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a118 len 9 flags 0x02
    [00:01:13.814,544] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:13.814,544] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:13.814,575] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:13.864,501] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.864,532] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:13.864,593] <dbg> bt_conn: tx_complete_work: conn 0x20002428
    [00:01:13.864,593] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.864,624] <dbg> bt_conn: tx_notify: tx 0x20003b8c cb 0x30eb9 user_data 0x20003c00
    [00:01:13.914,276] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:13.914,306] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:13.914,306] <dbg> bt_conn: bt_conn_recv: handle 0 len 5 flags 02
    [00:01:13.914,337] <dbg> bt_conn: bt_acl_recv: First, len 5 final 1
    [00:01:13.914,337] <dbg> bt_conn: bt_acl_recv: Successfully parsed 5 byte L2CAP packet
    [00:01:13.914,398] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:16.666,717] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:16.666,748] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:16.666,778] <dbg> bt_conn: bt_conn_recv: handle 0 len 16 flags 02
    [00:01:16.666,778] <dbg> bt_conn: bt_acl_recv: First, len 16 final 12
    [00:01:16.666,809] <dbg> bt_conn: bt_acl_recv: Successfully parsed 16 byte L2CAP packet
    [00:01:16.666,839] <dbg> bt_conn: bt_conn_send_cb: conn handle 0 buf len 10 cb 0 user_data 0
    [00:01:16.666,931] <dbg> bt_conn: bt_conn_process_tx: conn 0x20002428
    [00:01:16.666,931] <dbg> bt_conn: send_buf: conn 0x20002428 buf 0x2000a118 len 10
    [00:01:16.666,961] <dbg> bt_conn: send_buf: send single
    [00:01:16.666,961] <dbg> bt_conn: do_send_frag: conn 0x20002428 buf 0x2000a118 len 10 flags 0x02
    [00:01:16.667,083] <dbg> bt_conn: bt_conn_prepare_events: 
    [00:01:16.667,083] <dbg> bt_conn: conn_prepare_events: Adding conn 0x20002428 to poll list
    [00:01:16.667,114] <dbg> bt_conn: conn_prepare_events: wait on host fifo
    [00:01:16.667,144] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:16.714,538] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:16.714,599] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:17.013,946] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:17.013,977] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2
    [00:01:18.964,477] <dbg> bt_conn: bt_conn_ref: handle 0 ref 2 -> 3
    [00:01:18.964,508] <dbg> bt_conn: tx_notify: conn 0x20002428
    [00:01:18.964,508] <dbg> bt_conn: bt_conn_recv: handle 0 len 8 flags 02
    [00:01:18.964,538] <dbg> bt_conn: bt_acl_recv: First, len 8 final 4
    [00:01:18.964,538] <dbg> bt_conn: bt_acl_recv: Successfully parsed 8 byte L2CAP packet
    [00:01:18.964,569] <inf> main_log: notify_func_batt: Battery level | 100
    [00:01:18.964,843] <dbg> bt_conn: bt_conn_unref: handle 0 ref 3 -> 2

Related