[00:00:00.458,679] bt_hci_core: bt_hci_driver_register: Registered RPMsg *** Booting Zephyr OS build v3.1.99-ncs1-10-gd4b8ac4db1ed *** Starting Bluetooth multiple advertising sets example [00:00:00.477,233] bt_hci_core: hci_tx_thread: Started [00:00:00.483,551] bt_conn: bt_conn_prepare_events: [00:00:00.489,715] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:00.497,955] bt_hci_driver: bt_rpmsg_open: [00:00:00.504,913] bt_hci_core: bt_hci_cmd_create: opcode 0x0c03 param_len 0 [00:00:00.513,244] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:00.520,599] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x0c03 len 3 [00:00:00.530,059] bt_hci_core: process_events: count 2 [00:00:00.536,468] bt_hci_core: process_events: ev->state 4 [00:00:00.543,243] bt_hci_core: send_cmd: calling net_buf_get [00:00:00.550,201] [00:00:40.936,462] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:40.973,144] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:40.979,949] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:40.988,098] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:40.995,208] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:41.003,417] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:41.011,840] bt_hci_core: hci_event: event 0x3e [00:00:41.018,096] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:41.025,543] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:41.040,374] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:41.049,774] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:41.056,671] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:41.063,751] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:41.070,648] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:41.077,850] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:41.085,998] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:41.093,627] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:41.102,264] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:41.110,076] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:41.117,614] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:41.126,220] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:41.133,758] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:41.142,669] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:41.151,306] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:41.159,179] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:41.166,320] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:41.178,070] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:41.186,370] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:41.193,725] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:41.203,186] bt_hci_core: process_events: count 2 [00:00:41.209,625] bt_hci_core: process_events: ev->state 4 [00:00:41.216,400] bt_hci_core: send_cmd: calling net_buf_get [00:00:41.223,358] bt_hci_core: send_cmd: calling sem_take_wait [00:00:41.230,499] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:41.240,173] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:41.247,833] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:41.256,164] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:41.273,498] bt_hci_core: process_events: ev->state 1 [00:00:41.280,303] bt_conn: bt_conn_prepare_events: [00:00:41.286,437] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:41.295,501] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:41.303,741] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:41.330,902] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:41.347,564] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:41.354,278] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:41.362,426] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:41.369,415] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:41.386,444] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:41.403,045] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:41.409,790] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:41.417,907] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:41.424,926] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:41.434,692] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:41.441,680] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:41.450,256] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:41.457,397] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:41.474,395] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:41.490,997] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:41.497,711] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:41.505,859] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:41.512,847] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:41.519,836] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:41.529,113] bt_hci_core: opcode 0x2016 status 0x02 [00:00:41.535,980] bt_hci_core: Failed read remote features (-5) [00:00:41.543,487] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:41.551,818] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:41.559,173] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:41.568,756] bt_hci_core: process_events: count 3 [00:00:41.575,164] bt_hci_core: process_events: ev->state 4 [00:00:41.581,939] bt_hci_core: send_cmd: calling net_buf_get [00:00:41.588,897] bt_hci_core: send_cmd: calling sem_take_wait [00:00:41.596,069] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:41.605,743] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:41.613,494] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:41.621,917] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:41.639,343] bt_hci_core: process_events: ev->state 0 [00:00:41.646,118] bt_hci_core: process_events: ev->state 0 [00:00:41.652,923] bt_conn: bt_conn_prepare_events: [00:00:41.659,057] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:41.667,297] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:41.674,377] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:41.691,009] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:41.697,692] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:41.705,841] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:41.712,829] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:41.719,818] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:41.729,095] bt_hci_core: opcode 0x2032 status 0x02 [00:00:41.735,992] bt_hci_core: Failed LE Set PHY (-5) [00:00:41.742,584] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:41.749,786] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:41.757,995] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:41.766,357] bt_hci_core: hci_event: event 0x3e [00:00:41.772,583] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:41.779,846] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:41.790,710] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:41.802,398] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:41.809,600] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:41.817,810] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:41.826,141] bt_hci_core: hci_event: event 0x05 [00:00:41.832,397] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:41.841,735] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:41.848,724] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:41.857,574] bt_conn: tx_notify: conn 0x20000f00 [00:00:41.863,952] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:41.871,124] bt_hci_core: process_events: count 2 [00:00:41.877,563] bt_hci_core: process_events: ev->state 0 [00:00:41.884,338] bt_hci_core: process_events: ev->state 1 [00:00:41.891,143] bt_conn: bt_conn_prepare_events: [00:00:41.897,308] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:41.905,548] bt_conn: deferred_work: conn 0x20000f00 [00:00:41.912,261] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:41.920,898] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:41.928,985] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:41.937,591] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:41.946,228] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:41.954,376] bt_att: att_chan_detach: chan 0x20003d88 [00:00:41.961,212] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:41.968,566] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:41.980,346] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:41.987,518] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:41.994,750] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:42.003,143] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:42.010,040] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:42.018,371] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:42.025,756] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:42.035,217] bt_hci_core: process_events: count 2 [00:00:42.041,656] bt_hci_core: process_events: ev->state 4 [00:00:42.048,431] bt_hci_core: send_cmd: calling net_buf_get [00:00:42.055,358] bt_hci_core: send_cmd: calling sem_take_wait [00:00:42.062,530] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:42.072,204] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:42.079,833] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:42.088,195] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:42.105,590] bt_hci_core: process_events: ev->state 0 [00:00:42.112,396] bt_conn: bt_conn_prepare_events: [00:00:42.118,560] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:42.126,800] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:42.133,850] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:42.150,482] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:42.157,196] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:42.165,344] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:42.172,332] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:42.179,504] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:42.188,751] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:42.198,211] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:42.205,291] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:42.222,198] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:42.258,880] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:42.265,686] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:42.273,834] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 [00:00:42.280,944] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:42.289,154] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 [00:00:42.297,607] bt_hci_core: hci_event: event 0x3e [00:00:42.303,863] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:42.311,279] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:42.326,141] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:42.335,540] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:42.342,437] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:42.349,517] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:42.356,414] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:42.363,616] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:42.371,795] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:42.379,425] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:42.388,061] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:42.395,843] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:42.403,381] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:42.412,017] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:42.419,586] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:42.428,466] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:42.437,103] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:42.445,007] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:42.452,117] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:42.463,867] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:42.472,229] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:42.479,583] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:42.489,044] bt_hci_core: process_events: count 2 [00:00:42.495,452] bt_hci_core: process_events: ev->state 4 [00:00:42.502,227] bt_hci_core: send_cmd: calling net_buf_get [00:00:42.509,185] bt_hci_core: send_cmd: calling sem_take_wait [00:00:42.516,357] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:42.526,031] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:42.533,660] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:42.542,022] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:42.559,387] bt_hci_core: process_events: ev->state 1 [00:00:42.566,162] bt_conn: bt_conn_prepare_events: [00:00:42.572,326] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:42.581,390] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:42.589,630] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:42.616,790] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:42.633,453] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:42.640,197] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:42.648,345] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:42.655,334] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:42.672,332] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:42.688,964] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:42.695,678] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:42.703,826] bt_hci_core: bt_recv: buf 0x20006b70 len 6 [00:00:42.710,845] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:42.720,611] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:42.727,600] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:42.736,175] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:42.743,347] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:42.760,314] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:42.776,947] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:42.783,660] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:42.791,809] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:42.798,797] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:42.805,786] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:42.815,063] bt_hci_core: opcode 0x2016 status 0x02 [00:00:42.821,960] bt_hci_core: Failed read remote features (-5) [00:00:42.829,437] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:42.837,768] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:42.845,123] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:42.854,675] bt_hci_core: process_events: count 3 [00:00:42.861,114] bt_hci_core: process_events: ev->state 4 [00:00:42.867,889] bt_hci_core: send_cmd: calling net_buf_get [00:00:42.874,847] bt_hci_core: send_cmd: calling sem_take_wait [00:00:42.882,019] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:42.891,693] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:42.899,414] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:42.907,867] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:42.925,292] bt_hci_core: process_events: ev->state 0 [00:00:42.932,067] bt_hci_core: process_events: ev->state 0 [00:00:42.938,842] bt_conn: bt_conn_prepare_events: [00:00:42.945,007] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:42.953,247] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:42.960,296] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:42.976,928] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:42.983,612] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:42.991,760] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:42.998,779] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:43.005,767] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:43.015,014] bt_hci_core: opcode 0x2032 status 0x02 [00:00:43.021,911] bt_hci_core: Failed LE Set PHY (-5) [00:00:43.028,503] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:43.035,705] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:43.043,914] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:43.052,246] bt_hci_core: hci_event: event 0x3e [00:00:43.058,502] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:43.065,734] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:43.076,568] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:43.088,287] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:43.095,458] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:43.103,668] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 [00:00:43.112,030] bt_hci_core: hci_event: event 0x05 [00:00:43.118,286] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:43.127,593] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:43.134,582] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:43.143,432] bt_conn: tx_notify: conn 0x20000f00 [00:00:43.149,810] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:43.157,012] bt_hci_core: process_events: count 2 [00:00:43.163,421] bt_hci_core: process_events: ev->state 0 [00:00:43.170,196] bt_hci_core: process_events: ev->state 1 [00:00:43.176,971] bt_conn: bt_conn_prepare_events: [00:00:43.183,166] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:43.191,406] bt_conn: deferred_work: conn 0x20000f00 [00:00:43.198,120] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:43.206,756] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:43.214,813] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:43.223,449] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:43.232,086] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:43.240,234] bt_att: att_chan_detach: chan 0x20003d88 [00:00:43.247,039] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:43.254,425] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:43.266,204] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:43.273,345] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:43.280,609] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:43.289,001] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:43.295,898] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:43.304,229] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:43.311,614] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:43.321,105] bt_hci_core: process_events: count 2 [00:00:43.327,514] bt_hci_core: process_events: ev->state 4 [00:00:43.334,320] bt_hci_core: send_cmd: calling net_buf_get [00:00:43.341,278] bt_hci_core: send_cmd: calling sem_take_wait [00:00:43.348,449] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:43.358,123] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:43.365,753] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:43.374,114] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:43.391,540] bt_hci_core: process_events: ev->state 0 [00:00:43.398,315] bt_conn: bt_conn_prepare_events: [00:00:43.404,479] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:43.412,719] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:43.419,769] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:43.436,401] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:43.443,084] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:43.451,232] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:43.458,221] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:43.465,393] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:43.474,670] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:43.484,100] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:43.491,210] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:43.508,117] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:43.544,830] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:43.551,635] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:43.559,783] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:43.566,894] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:43.575,103] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:43.583,557] bt_hci_core: hci_event: event 0x3e [00:00:43.589,813] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:43.597,259] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:43.612,121] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:43.621,490] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:43.628,387] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:43.635,467] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:43.642,395] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:43.649,597] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:43.657,775] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:43.665,405] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:43.674,041] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:43.681,823] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:43.689,361] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:43.697,998] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:43.705,566] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:43.714,447] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:43.723,083] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:43.730,987] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:43.738,098] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:43.749,847] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:43.758,178] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:43.765,533] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:43.774,993] bt_hci_core: process_events: count 2 [00:00:43.781,433] bt_hci_core: process_events: ev->state 4 [00:00:43.788,208] bt_hci_core: send_cmd: calling net_buf_get [00:00:43.795,166] bt_hci_core: send_cmd: calling sem_take_wait [00:00:43.802,307] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:43.812,011] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:43.819,641] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:43.827,972] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:43.845,336] bt_hci_core: process_events: ev->state 1 [00:00:43.852,111] bt_conn: bt_conn_prepare_events: [00:00:43.858,276] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:43.867,309] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:43.875,549] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:43.902,740] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:43.919,372] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:43.926,116] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:43.934,265] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:43.941,253] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:43.958,282] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:43.974,884] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:43.981,597] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:43.989,746] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:43.996,765] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:44.006,530] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:44.013,519] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:44.022,094] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:44.029,266] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:44.046,234] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:44.062,866] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:44.069,549] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:44.077,697] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:44.084,686] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:44.091,674] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:44.100,952] bt_hci_core: opcode 0x2016 status 0x02 [00:00:44.107,849] bt_hci_core: Failed read remote features (-5) [00:00:44.115,356] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:44.123,687] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:44.131,042] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:44.140,594] bt_hci_core: process_events: count 3 [00:00:44.147,003] bt_hci_core: process_events: ev->state 4 [00:00:44.153,808] bt_hci_core: send_cmd: calling net_buf_get [00:00:44.160,736] bt_hci_core: send_cmd: calling sem_take_wait [00:00:44.167,907] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:44.177,581] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:44.185,302] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:44.193,756] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:44.211,181] bt_hci_core: process_events: ev->state 0 [00:00:44.217,956] bt_hci_core: process_events: ev->state 0 [00:00:44.224,731] bt_conn: bt_conn_prepare_events: [00:00:44.230,895] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:44.239,135] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:44.246,215] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:44.262,847] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:44.269,531] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:44.277,679] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:44.284,698] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:44.291,687] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:44.300,964] bt_hci_core: opcode 0x2032 status 0x02 [00:00:44.307,830] bt_hci_core: Failed LE Set PHY (-5) [00:00:44.314,453] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:44.321,624] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:44.329,833] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:44.338,195] bt_hci_core: hci_event: event 0x3e [00:00:44.344,451] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:44.351,715] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:44.362,548] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:44.374,237] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:44.381,439] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:44.389,648] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:44.397,979] bt_hci_core: hci_event: event 0x05 [00:00:44.404,266] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:44.413,574] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:44.420,562] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:44.429,412] bt_conn: tx_notify: conn 0x20000f00 [00:00:44.435,791] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:44.442,993] bt_hci_core: process_events: count 2 [00:00:44.449,401] bt_hci_core: process_events: ev->state 0 [00:00:44.456,207] bt_hci_core: process_events: ev->state 1 [00:00:44.462,982] bt_conn: bt_conn_prepare_events: [00:00:44.469,146] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:44.477,386] bt_conn: deferred_work: conn 0x20000f00 [00:00:44.484,100] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:44.492,736] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:44.500,823] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:44.509,429] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:44.518,066] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:44.526,214] bt_att: att_chan_detach: chan 0x20003d88 [00:00:44.533,050] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:44.540,405] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:44.552,185] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:44.559,356] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:44.566,589] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:44.574,981] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:44.581,909] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:44.590,240] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:44.597,595] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:44.607,086] bt_hci_core: process_events: count 2 [00:00:44.613,494] bt_hci_core: process_events: ev->state 4 [00:00:44.620,269] bt_hci_core: send_cmd: calling net_buf_get [00:00:44.627,227] bt_hci_core: send_cmd: calling sem_take_wait [00:00:44.634,399] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:44.644,073] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:44.651,702] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:44.660,064] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:44.677,459] bt_hci_core: process_events: ev->state 0 [00:00:44.684,265] bt_conn: bt_conn_prepare_events: [00:00:44.690,399] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:44.698,638] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:44.705,718] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:44.722,320] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:44.729,034] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:44.737,182] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:44.744,171] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:44.751,342] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:44.760,620] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:44.770,050] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:44.777,160] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:44.794,067] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:44.830,749] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:44.837,554] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:44.845,703] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 [00:00:44.852,813] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:44.861,022] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 [00:00:44.869,445] bt_hci_core: hci_event: event 0x3e [00:00:44.875,701] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:44.883,148] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:44.897,979] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:44.907,379] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:44.914,276] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:44.921,356] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:44.928,253] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:44.935,455] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:44.943,634] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:44.951,263] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:44.959,869] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:44.967,681] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:44.975,189] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:44.983,825] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:44.991,394] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:45.000,305] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:45.008,911] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:45.016,815] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:45.023,925] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:45.035,675] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:45.044,006] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:45.051,361] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:45.060,821] bt_hci_core: process_events: count 2 [00:00:45.067,230] bt_hci_core: process_events: ev->state 4 [00:00:45.074,005] bt_hci_core: send_cmd: calling net_buf_get [00:00:45.080,963] bt_hci_core: send_cmd: calling sem_take_wait [00:00:45.088,104] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:45.097,778] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:45.105,407] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:45.113,739] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:45.131,103] bt_hci_core: process_events: ev->state 1 [00:00:45.137,878] bt_conn: bt_conn_prepare_events: [00:00:45.144,042] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:45.153,076] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:45.161,315] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:45.188,476] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:45.205,139] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:45.211,853] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:45.220,001] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:45.227,020] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:45.244,018] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:45.260,650] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:45.267,364] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:45.275,512] bt_hci_core: bt_recv: buf 0x20006b70 len 6 [00:00:45.282,531] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:45.292,297] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:45.299,255] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:45.307,861] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:45.315,002] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:45.332,000] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:45.348,602] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:45.355,316] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:45.363,464] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:45.370,452] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:45.377,441] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:45.386,718] bt_hci_core: opcode 0x2016 status 0x02 [00:00:45.393,615] bt_hci_core: Failed read remote features (-5) [00:00:45.401,123] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:45.409,454] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:45.416,809] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:45.426,361] bt_hci_core: process_events: count 3 [00:00:45.432,800] bt_hci_core: process_events: ev->state 4 [00:00:45.439,605] bt_hci_core: send_cmd: calling net_buf_get [00:00:45.446,563] bt_hci_core: send_cmd: calling sem_take_wait [00:00:45.453,735] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:45.463,409] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:45.471,130] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:45.479,583] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:45.497,009] bt_hci_core: process_events: ev->state 0 [00:00:45.503,784] bt_hci_core: process_events: ev->state 0 [00:00:45.510,589] bt_conn: bt_conn_prepare_events: [00:00:45.516,723] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:45.524,993] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:45.532,043] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:45.548,675] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:45.555,389] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:45.563,537] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:45.570,526] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:45.577,514] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:45.586,791] bt_hci_core: opcode 0x2032 status 0x02 [00:00:45.593,688] bt_hci_core: Failed LE Set PHY (-5) [00:00:45.600,311] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:45.607,482] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:45.615,692] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:45.624,053] bt_hci_core: hci_event: event 0x3e [00:00:45.630,310] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:45.637,542] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:45.648,406] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:45.660,095] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:45.667,297] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:45.675,506] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 [00:00:45.683,837] bt_hci_core: hci_event: event 0x05 [00:00:45.690,093] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:45.699,432] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:45.706,390] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:45.715,240] bt_conn: tx_notify: conn 0x20000f00 [00:00:45.721,618] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:45.728,820] bt_hci_core: process_events: count 2 [00:00:45.735,229] bt_hci_core: process_events: ev->state 0 [00:00:45.742,034] bt_hci_core: process_events: ev->state 1 [00:00:45.748,809] bt_conn: bt_conn_prepare_events: [00:00:45.754,974] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:45.763,214] bt_conn: deferred_work: conn 0x20000f00 [00:00:45.769,958] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:45.778,564] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:45.786,651] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:45.795,288] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:45.803,924] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:45.812,072] bt_att: att_chan_detach: chan 0x20003d88 [00:00:45.818,878] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:45.826,263] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:45.838,012] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:45.845,184] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:45.852,416] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:45.860,839] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:45.867,736] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:45.876,068] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:45.883,422] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:45.892,883] bt_hci_core: process_events: count 2 [00:00:45.899,322] bt_hci_core: process_events: ev->state 4 [00:00:45.906,097] bt_hci_core: send_cmd: calling net_buf_get [00:00:45.913,055] bt_hci_core: send_cmd: calling sem_take_wait [00:00:45.920,227] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:45.929,931] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:45.937,561] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:45.945,892] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:45.963,317] bt_hci_core: process_events: ev->state 0 [00:00:45.970,092] bt_conn: bt_conn_prepare_events: [00:00:45.976,257] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:45.984,497] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:45.991,546] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:46.008,178] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:46.014,862] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:46.023,010] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:46.029,998] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:46.037,170] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:46.046,417] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:46.055,877] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:46.062,957] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:46.079,864] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:46.116,546] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:46.123,352] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:46.131,500] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:46.138,610] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:46.146,820] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:46.155,242] bt_hci_core: hci_event: event 0x3e [00:00:46.161,499] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:46.168,945] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:46.183,807] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:46.193,206] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:46.200,103] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:46.207,183] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:46.214,080] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:46.221,282] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:46.229,461] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:46.237,091] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:46.245,727] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:46.253,509] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:46.261,047] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:46.269,683] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:46.277,221] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:46.286,132] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:46.294,769] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:46.302,642] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:46.309,753] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:46.321,533] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:46.329,864] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:46.337,219] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:46.346,679] bt_hci_core: process_events: count 2 [00:00:46.353,088] bt_hci_core: process_events: ev->state 4 [00:00:46.359,893] bt_hci_core: send_cmd: calling net_buf_get [00:00:46.366,851] bt_hci_core: send_cmd: calling sem_take_wait [00:00:46.373,992] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:46.383,666] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:46.391,296] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:46.399,658] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:46.417,022] bt_hci_core: process_events: ev->state 1 [00:00:46.423,797] bt_conn: bt_conn_prepare_events: [00:00:46.429,931] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:46.438,995] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:46.447,235] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:46.474,395] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:46.491,058] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:46.497,772] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:46.505,920] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:46.512,939] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:46.529,937] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:46.546,539] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:46.553,283] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:46.561,431] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:46.568,420] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:46.578,186] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:46.585,174] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:46.593,750] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:46.600,921] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:46.617,889] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:46.634,521] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:46.641,235] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:46.649,383] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:46.656,372] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:46.663,360] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:46.672,637] bt_hci_core: opcode 0x2016 status 0x02 [00:00:46.679,534] bt_hci_core: Failed read remote features (-5) [00:00:46.687,011] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:46.695,343] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:46.702,697] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:46.712,280] bt_hci_core: process_events: count 3 [00:00:46.718,688] bt_hci_core: process_events: ev->state 4 [00:00:46.725,494] bt_hci_core: send_cmd: calling net_buf_get [00:00:46.732,421] bt_hci_core: send_cmd: calling sem_take_wait [00:00:46.739,593] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:46.749,298] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:46.757,019] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:46.765,441] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:46.782,867] bt_hci_core: process_events: ev->state 0 [00:00:46.789,672] bt_hci_core: process_events: ev->state 0 [00:00:46.796,447] bt_conn: bt_conn_prepare_events: [00:00:46.802,612] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:46.810,852] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:46.817,901] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:46.834,533] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:46.841,247] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:46.849,395] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:46.856,384] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:46.863,372] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:46.872,650] bt_hci_core: opcode 0x2032 status 0x02 [00:00:46.879,547] bt_hci_core: Failed LE Set PHY (-5) [00:00:46.886,138] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:46.893,341] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:46.901,550] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:46.909,912] bt_hci_core: hci_event: event 0x3e [00:00:46.916,168] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:46.923,400] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:46.934,265] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:46.945,953] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:46.953,155] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:46.961,364] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:46.969,726] bt_hci_core: hci_event: event 0x05 [00:00:46.975,982] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:46.985,290] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:46.992,279] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:47.001,129] bt_conn: tx_notify: conn 0x20000f00 [00:00:47.007,507] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:47.014,709] bt_hci_core: process_events: count 2 [00:00:47.021,118] bt_hci_core: process_events: ev->state 0 [00:00:47.027,893] bt_hci_core: process_events: ev->state 1 [00:00:47.034,667] bt_conn: bt_conn_prepare_events: [00:00:47.040,863] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:47.049,102] bt_conn: deferred_work: conn 0x20000f00 [00:00:47.055,816] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:47.064,453] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:47.072,509] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:47.081,115] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:47.089,752] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:47.097,900] bt_att: att_chan_detach: chan 0x20003d88 [00:00:47.104,705] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:47.112,091] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:47.123,840] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:47.131,011] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:47.138,244] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:47.146,667] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:47.153,564] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:47.161,895] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:47.169,250] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:47.178,741] bt_hci_core: process_events: count 2 [00:00:47.185,180] bt_hci_core: process_events: ev->state 4 [00:00:47.191,955] bt_hci_core: send_cmd: calling net_buf_get [00:00:47.198,913] bt_hci_core: send_cmd: calling sem_take_wait [00:00:47.206,085] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:47.215,789] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:47.223,419] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:47.231,750] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:47.249,176] bt_hci_core: process_events: ev->state 0 [00:00:47.255,950] bt_conn: bt_conn_prepare_events: [00:00:47.262,115] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:47.270,355] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:47.277,404] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:47.294,036] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:47.300,720] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:47.308,868] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:47.315,887] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:47.323,028] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:47.332,305] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:47.341,766] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:47.348,876] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:47.677,093] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:47.713,775] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:47.720,611] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:47.728,759] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 [00:00:47.735,839] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:47.744,079] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 [00:00:47.752,502] bt_hci_core: hci_event: event 0x3e [00:00:47.758,758] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:47.766,174] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:47.781,036] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:47.790,405] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:47.797,332] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:47.804,412] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:47.811,279] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:47.818,511] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:47.826,660] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:47.834,289] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:47.842,926] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:47.850,738] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:47.858,245] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:47.866,882] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:47.874,450] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:47.883,361] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:47.891,967] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:47.899,871] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:47.906,982] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:47.918,762] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:47.927,093] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:47.934,448] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:47.943,908] bt_hci_core: process_events: count 2 [00:00:47.950,317] bt_hci_core: process_events: ev->state 4 [00:00:47.957,122] bt_hci_core: send_cmd: calling net_buf_get [00:00:47.964,080] bt_hci_core: send_cmd: calling sem_take_wait [00:00:47.971,221] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:47.980,895] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:47.988,525] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:47.996,856] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:48.014,221] bt_hci_core: process_events: ev->state 1 [00:00:48.020,996] bt_conn: bt_conn_prepare_events: [00:00:48.027,160] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:48.036,193] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:48.044,433] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:48.071,624] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:48.088,256] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:48.094,970] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:48.103,118] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:48.110,137] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:48.127,136] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:48.143,768] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:48.150,482] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:48.158,630] bt_hci_core: bt_recv: buf 0x20006b70 len 6 [00:00:48.165,649] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:48.175,415] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:48.182,403] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:48.190,979] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:48.198,150] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:48.215,118] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:48.231,750] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:48.238,433] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:48.246,582] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:48.253,570] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:48.260,559] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:48.269,836] bt_hci_core: opcode 0x2016 status 0x02 [00:00:48.276,733] bt_hci_core: Failed read remote features (-5) [00:00:48.284,240] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:48.292,572] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:48.299,926] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:48.309,509] bt_hci_core: process_events: count 3 [00:00:48.315,917] bt_hci_core: process_events: ev->state 4 [00:00:48.322,723] bt_hci_core: send_cmd: calling net_buf_get [00:00:48.329,681] bt_hci_core: send_cmd: calling sem_take_wait [00:00:48.336,822] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:48.346,527] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:48.354,248] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:48.362,701] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:48.380,157] bt_hci_core: process_events: ev->state 0 [00:00:48.386,932] bt_hci_core: process_events: ev->state 0 [00:00:48.393,737] bt_conn: bt_conn_prepare_events: [00:00:48.399,871] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:48.408,111] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:48.415,191] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:48.431,823] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:48.438,507] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:48.446,655] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:48.453,674] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:48.460,662] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:48.469,940] bt_hci_core: opcode 0x2032 status 0x02 [00:00:48.476,806] bt_hci_core: Failed LE Set PHY (-5) [00:00:48.483,428] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:48.490,600] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:48.498,809] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:48.507,171] bt_hci_core: hci_event: event 0x3e [00:00:48.513,397] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:48.520,660] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:48.531,524] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:48.543,212] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:48.550,415] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:48.558,624] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 [00:00:48.566,986] bt_hci_core: hci_event: event 0x05 [00:00:48.573,242] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:48.582,550] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:48.589,538] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:48.598,388] bt_conn: tx_notify: conn 0x20000f00 [00:00:48.604,766] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:48.611,968] bt_hci_core: process_events: count 2 [00:00:48.618,408] bt_hci_core: process_events: ev->state 0[0m [00:00:48.625,183] bt_hci_core: process_events: ev->state 1 [00:00:48.631,958] bt_conn: bt_conn_prepare_events: [00:00:48.638,153] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:48.646,392] bt_conn: deferred_work: conn 0x20000f00 [00:00:48.653,106] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:48.661,743] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:48.669,799] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:48.678,436] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:48.687,042] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:48.695,220] bt_att: att_chan_detach: chan 0x20003d88 [00:00:48.702,026] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:48.709,411] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:48.721,160] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:48.728,332] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:48.735,595] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:48.743,988] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:48.750,885] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:48.759,216] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:48.766,571] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:48.776,062] bt_hci_core: process_events: count 2 [00:00:48.782,470] bt_hci_core: process_events: ev->state 4 [00:00:48.789,276] bt_hci_core: send_cmd: calling net_buf_get [00:00:48.796,203] bt_hci_core: send_cmd: calling sem_take_wait [00:00:48.803,375] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:48.813,079] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:48.820,709] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:48.829,040] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:48.846,466] bt_hci_core: process_events: ev->state 0 [00:00:48.853,240] bt_conn: bt_conn_prepare_events: [00:00:48.859,405] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:48.867,645] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:48.874,694] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:48.891,326] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:48.898,010] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:48.906,158] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:48.913,177] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:48.920,318] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50[0m [00:00:48.929,595] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:48.939,056] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:48.946,136] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:49.172,943] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:49.209,625] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:49.216,430] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:49.224,578] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:49.231,689] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:49.239,898] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:49.248,321] bt_hci_core: hci_event: event 0x3e [00:00:49.254,577] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:49.262,023] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:49.276,855] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:49.286,254] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:49.293,151] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:49.300,231] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:49.307,128] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:49.314,331] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:49.322,509] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:49.330,139] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:49.338,745] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:49.346,557] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:49.354,064] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:49.362,701] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:49.370,239] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:49.379,150] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:49.387,786] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:49.395,690] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:49.402,801] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:49.414,550] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:49.422,882] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:49.430,236] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:49.439,697] bt_hci_core: process_events: count 2 [00:00:49.446,136] bt_hci_core: process_events: ev->state 4 [00:00:49.452,911] bt_hci_core: send_cmd: calling net_buf_get [00:00:49.459,869] bt_hci_core: send_cmd: calling sem_take_wait [00:00:49.467,010] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:49.476,715] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:49.484,344] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:49.492,675] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:49.510,040] bt_hci_core: process_events: ev->state 1 [00:00:49.516,815] bt_conn: bt_conn_prepare_events: [00:00:49.522,979] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:49.532,043] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:49.540,283] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:49.567,443] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:49.584,106] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:49.590,820] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:49.598,968] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:49.605,957] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:49.622,985] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:49.639,617] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:49.646,331] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:49.654,479] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:49.661,499] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:49.671,264] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:49.678,253] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:49.686,828] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:49.694,000] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:49.710,968] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:49.727,600] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:49.734,313] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:49.742,462] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:49.749,450] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:49.756,439] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:49.765,716] bt_hci_core: opcode 0x2016 status 0x02 [00:00:49.772,583] bt_hci_core: Failed read remote features (-5) [00:00:49.780,090] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:49.788,421] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:49.795,776] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:49.805,328] bt_hci_core: process_events: count 3 [00:00:49.811,767] bt_hci_core: process_events: ev->state 4 [00:00:49.818,542] bt_hci_core: send_cmd: calling net_buf_get [00:00:49.825,500] bt_hci_core: send_cmd: calling sem_take_wait [00:00:49.832,672] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:49.842,346] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:49.850,067] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:49.858,520] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:49.875,946] bt_hci_core: process_events: ev->state 0 [00:00:49.882,720] bt_hci_core: process_events: ev->state 0 [00:00:49.889,495] bt_conn: bt_conn_prepare_events: [00:00:49.895,660] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:49.903,900] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:49.910,949] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:49.927,581] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:49.934,265] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:49.942,413] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:49.949,401] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:49.956,390] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:49.965,698] bt_hci_core: opcode 0x2032 status 0x02 [00:00:49.972,564] bt_hci_core: Failed LE Set PHY (-5) [00:00:49.979,187] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:49.986,389] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:49.994,567] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:50.002,929] bt_hci_core: hci_event: event 0x3e [00:00:50.009,185] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:50.016,418] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:50.027,252] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:50.038,940] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:50.046,142] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:50.054,351] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:50.062,683] bt_hci_core: hci_event: event 0x05 [00:00:50.068,939] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:50.078,277] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:50.085,235] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:50.094,085] bt_conn: tx_notify: conn 0x20000f00 [00:00:50.100,463] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:50.107,666] bt_hci_core: process_events: count 2 [00:00:50.114,074] bt_hci_core: process_events: ev->state 0 [00:00:50.120,849] bt_hci_core: process_events: ev->state 1 [00:00:50.127,624] bt_conn: bt_conn_prepare_events: [00:00:50.133,819] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:50.142,059] bt_conn: deferred_work: conn 0x20000f00 [00:00:50.148,773] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:50.157,379] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:50.165,466] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:50.174,072] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:50.182,708] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:50.190,856] bt_att: att_chan_detach: chan 0x20003d88 [00:00:50.197,692] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:50.205,047] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:50.216,827] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:50.223,968] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:50.231,231] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:50.239,624] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:50.246,520] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:50.254,852] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:50.262,237] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:50.271,697] bt_hci_core: process_events: count 2 [00:00:50.278,137] bt_hci_core: process_events: ev->state 4 [00:00:50.284,912] bt_hci_core: send_cmd: calling net_buf_get [00:00:50.291,870] bt_hci_core: send_cmd: calling sem_take_wait [00:00:50.299,041] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:50.308,746] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:50.316,375] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:50.324,707] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:50.342,132] bt_hci_core: process_events: ev->state 0 [00:00:50.348,937] bt_conn: bt_conn_prepare_events: [00:00:50.355,072] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:50.363,311] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:50.370,391] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:50.387,023] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:50.393,707] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:50.401,855] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:50.408,843] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:50.416,015] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:50.425,292] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:50.434,753] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:50.441,833] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:50.980,407] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:51.017,089] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:51.023,895] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:51.032,043] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 [00:00:51.039,123] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:51.047,332] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 [00:00:51.055,755] bt_hci_core: hci_event: event 0x3e [00:00:51.062,011] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:51.069,427] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:51.084,289] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:51.093,688] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:51.100,555] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:51.107,635] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:51.114,532] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:51.121,765] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:51.129,913] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:51.137,542] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:51.146,179] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:51.153,961] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:51.161,499] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:51.170,135] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:51.177,673] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:51.186,584] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:51.195,220] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:51.203,125] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:51.210,235] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:51.221,984] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:51.230,316] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:51.237,670] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:51.247,131] bt_hci_core: process_events: count 2 [00:00:51.253,540] bt_hci_core: process_events: ev->state 4 [00:00:51.260,345] bt_hci_core: send_cmd: calling net_buf_get [00:00:51.267,303] bt_hci_core: send_cmd: calling sem_take_wait [00:00:51.274,444] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:51.284,118] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:51.291,748] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:51.300,109] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:51.317,474] bt_hci_core: process_events: ev->state 1 [00:00:51.324,249] bt_conn: bt_conn_prepare_events: [00:00:51.330,383] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:51.339,447] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:51.347,686] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:51.374,847] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:51.391,510] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:51.398,223] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:51.406,372] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:51.413,391] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:51.430,389] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:51.447,021] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:51.453,735] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:51.461,914] bt_hci_core: bt_recv: buf 0x20006b70 len 6 [00:00:51.468,902] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:51.478,668] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:51.485,656] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:51.494,232] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:51.501,403] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:51.518,402] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:51.535,034] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:51.541,717] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:51.549,865] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:51.556,854] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:51.563,842] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:51.573,120] bt_hci_core: opcode 0x2016 status 0x02 [00:00:51.579,986] bt_hci_core: Failed read remote features (-5) [00:00:51.587,493] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:51.595,825] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:51.603,179] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:51.612,731] bt_hci_core: process_events: count 3 [00:00:51.619,140] bt_hci_core: process_events: ev->state 4 [00:00:51.625,946] bt_hci_core: send_cmd: calling net_buf_get [00:00:51.632,873] bt_hci_core: send_cmd: calling sem_take_wait [00:00:51.640,045] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:51.649,719] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:51.657,440] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:51.665,893] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:51.683,319] bt_hci_core: process_events: ev->state 0 [00:00:51.690,093] bt_hci_core: process_events: ev->state 0 [00:00:51.696,899] bt_conn: bt_conn_prepare_events: [00:00:51.703,033] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:51.711,273] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:51.718,353] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:51.734,985] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:51.741,699] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:51.749,847] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:51.756,835] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:51.763,824] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:51.773,101] bt_hci_core: opcode 0x2032 status 0x02 [00:00:51.779,998] bt_hci_core: Failed LE Set PHY (-5) [00:00:51.786,590] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:51.793,792] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:51.802,001] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:51.810,363] bt_hci_core: hci_event: event 0x3e [00:00:51.816,619] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:51.823,852] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:51.834,716] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:51.846,405] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:51.853,607] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:51.861,816] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 [00:00:51.870,147] bt_hci_core: hci_event: event 0x05 [00:00:51.876,403] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:51.885,742] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:51.892,700] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:51.901,550] bt_conn: tx_notify: conn 0x20000f00 [00:00:51.907,928] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:51.915,130] bt_hci_core: process_events: count 2 [00:00:51.921,569] bt_hci_core: process_events: ev->state 0 [00:00:51.928,344] bt_hci_core: process_events: ev->state 1 [00:00:51.935,119] bt_conn: bt_conn_prepare_events: [00:00:51.941,314] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:51.949,523] bt_conn: deferred_work: conn 0x20000f00 [00:00:51.956,268] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:51.964,874] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:51.972,961] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:51.981,597] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:51.990,203] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:51.998,382] bt_att: att_chan_detach: chan 0x20003d88 [00:00:52.005,187] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:52.012,573] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:52.024,353] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:52.031,494] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:52.038,726] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:52.047,149] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:52.054,046] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:52.062,347] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:52.069,732] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:52.079,193] bt_hci_core: process_events: count 2 [00:00:52.085,632] bt_hci_core: process_events: ev->state 4 [00:00:52.092,407] bt_hci_core: send_cmd: calling net_buf_get [00:00:52.099,334] bt_hci_core: send_cmd: calling sem_take_wait [00:00:52.106,506] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:52.116,210] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:52.123,840] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:52.132,171] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:52.149,597] bt_hci_core: process_events: ev->state 0 [00:00:52.156,372] bt_conn: bt_conn_prepare_events: [00:00:52.162,536] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:52.170,776] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:52.177,856] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:52.194,458] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:52.201,171] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:52.209,320] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:52.216,308] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:52.223,480] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:52.232,757] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:52.242,187] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:52.249,298] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:52.481,872] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:52.518,554] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:52.525,360] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:52.533,508] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:52.540,618] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:52.548,828] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:52.557,281] bt_hci_core: hci_event: event 0x3e [00:00:52.563,537] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:52.570,983] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:52.585,845] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:52.595,214] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:52.602,111] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:52.609,191] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:52.616,088] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:52.623,321] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:52.631,469] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:52.639,099] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:52.647,735] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:52.655,548] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:52.663,085] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:52.671,722] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:52.679,260] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:52.688,171] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:52.696,807] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:52.704,681] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:52.711,791] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:52.723,571] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:52.731,903] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:52.739,257] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:52.748,718] bt_hci_core: process_events: count 2 [00:00:52.755,126] bt_hci_core: process_events: ev->state 4 [00:00:52.761,932] bt_hci_core: send_cmd: calling net_buf_get [00:00:52.768,890] bt_hci_core: send_cmd: calling sem_take_wait [00:00:52.776,031] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:52.785,705] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:52.793,365] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:52.801,696] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:52.819,061] bt_hci_core: process_events: ev->state 1 [00:00:52.825,836] bt_conn: bt_conn_prepare_events: [00:00:52.832,000] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:52.841,033] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:52.849,243] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:52.876,464] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:52.893,096] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:52.899,810] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:52.907,958] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:52.914,978] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:52.931,976] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:52.948,608] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:52.955,322] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:52.963,470] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:52.970,489] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:52.980,255] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:52.987,243] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:52.995,819] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:53.002,990] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:53.019,958] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:53.036,590] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:53.043,304] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:53.051,422] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:53.058,441] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:53.065,429] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:53.074,676] bt_hci_core: opcode 0x2016 status 0x02 [00:00:53.081,573] bt_hci_core: Failed read remote features (-5) [00:00:53.089,080] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:53.097,381] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:53.104,736] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:53.114,318] bt_hci_core: process_events: count 3 [00:00:53.120,727] bt_hci_core: process_events: ev->state 4 [00:00:53.127,532] bt_hci_core: send_cmd: calling net_buf_get [00:00:53.134,490] bt_hci_core: send_cmd: calling sem_take_wait [00:00:53.141,662] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:53.151,336] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:53.159,057] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:53.167,480] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:53.184,936] bt_hci_core: process_events: ev->state 0 [00:00:53.191,711] bt_hci_core: process_events: ev->state 0 [00:00:53.198,486] bt_conn: bt_conn_prepare_events: [00:00:53.204,650] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:53.212,890] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:53.219,970] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:53.236,602] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:53.243,286] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:53.251,434] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:53.258,453] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:53.265,441] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:53.274,749] bt_hci_core: opcode 0x2032 status 0x02 [00:00:53.281,646] bt_hci_core: Failed LE Set PHY (-5) [00:00:53.288,238] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:53.295,440] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:53.303,649] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:53.312,011] bt_hci_core: hci_event: event 0x3e [00:00:53.318,237] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:53.325,500] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:53.336,334] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:53.348,052] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:53.355,224] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:53.363,433] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:53.371,795] bt_hci_core: hci_event: event 0x05 [00:00:53.378,051] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:53.387,390] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:53.394,378] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:53.403,198] bt_conn: tx_notify: conn 0x20000f00 [00:00:53.409,606] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:53.416,809] bt_hci_core: process_events: count 2 [00:00:53.423,217] bt_hci_core: process_events: ev->state 0 [00:00:53.430,023] bt_hci_core: process_events: ev->state 1 [00:00:53.436,798] bt_conn: bt_conn_prepare_events: [00:00:53.442,962] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:53.451,202] bt_conn: deferred_work: conn 0x20000f00 [00:00:53.457,916] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:53.466,552] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:53.474,639] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:53.483,276] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:53.491,882] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:53.500,061] bt_att: att_chan_detach: chan 0x20003d88 [00:00:53.506,866] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:53.514,251] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:53.526,000] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:53.533,172] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:53.540,405] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:53.548,797] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:53.555,725] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:53.564,025] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:53.571,380] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:53.580,902] bt_hci_core: process_events: count 2 [00:00:53.587,310] bt_hci_core: process_events: ev->state 4 [00:00:53.594,116] bt_hci_core: send_cmd: calling net_buf_get [00:00:53.601,043] bt_hci_core: send_cmd: calling sem_take_wait [00:00:53.608,215] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:53.617,919] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:53.625,549] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:53.633,880] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:53.651,306] bt_hci_core: process_events: ev->state 0 [00:00:53.658,081] bt_conn: bt_conn_prepare_events: [00:00:53.664,215] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:53.672,454] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:53.679,534] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:53.696,136] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:53.702,850] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:53.710,998] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:53.717,987] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:53.725,158] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:53.734,436] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:53.743,865] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:53.750,976] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:53.984,313] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:54.020,996] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:54.027,832] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:54.035,980] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 [00:00:54.043,090] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:54.051,269] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 [00:00:54.059,722] bt_hci_core: hci_event: event 0x3e [00:00:54.065,948] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:54.073,394] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:54.088,256] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:54.097,625] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:54.104,553] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:54.111,602] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:54.118,530] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:54.125,732] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:54.133,880] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:54.141,510] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:54.150,146] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:54.157,958] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:54.165,496] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:54.174,133] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:54.181,671] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:54.190,582] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:54.199,218] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:54.207,122] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:54.214,233] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:54.225,982] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:54.234,313] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:54.241,668] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:54.251,129] bt_hci_core: process_events: count 2 [00:00:54.257,568] bt_hci_core: process_events: ev->state 4 [00:00:54.264,343] bt_hci_core: send_cmd: calling net_buf_get [00:00:54.271,301] bt_hci_core: send_cmd: calling sem_take_wait [00:00:54.278,472] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:54.288,146] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:54.295,776] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:54.304,138] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:54.321,472] bt_hci_core: process_events: ev->state 1 [00:00:54.328,277] bt_conn: bt_conn_prepare_events: [00:00:54.334,442] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:54.343,475] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:54.351,715] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:54.378,906] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:54.395,568] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:54.402,282] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:54.410,430] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:54.417,449] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:54.434,478] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:54.451,080] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:54.457,824] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:54.465,972] bt_hci_core: bt_recv: buf 0x20006b70 len 6 [00:00:54.472,961] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:54.482,727] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:54.489,715] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:54.498,291] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:54.505,462] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:54.522,460] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:54.539,093] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:54.545,776] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:54.553,924] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:54.560,943] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:54.567,901] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:54.577,178] bt_hci_core: opcode 0x2016 status 0x02 [00:00:54.584,075] bt_hci_core: Failed read remote features (-5) [00:00:54.591,583] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:54.599,914] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:54.607,269] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:54.616,821] bt_hci_core: process_events: count 3 [00:00:54.623,260] bt_hci_core: process_events: ev->state 4 [00:00:54.630,035] bt_hci_core: send_cmd: calling net_buf_get [00:00:54.636,993] bt_hci_core: send_cmd: calling sem_take_wait [00:00:54.644,165] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:54.653,839] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:54.661,560] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:54.669,982] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:54.687,408] bt_hci_core: process_events: ev->state 0 [00:00:54.694,213] bt_hci_core: process_events: ev->state 0 [00:00:54.700,988] bt_conn: bt_conn_prepare_events: [00:00:54.707,153] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:54.715,393] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:54.722,442] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:54.739,074] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:54.745,788] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:54.753,936] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:54.760,925] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:54.767,944] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:54.777,221] bt_hci_core: opcode 0x2032 status 0x02 [00:00:54.784,118] bt_hci_core: Failed LE Set PHY (-5) [00:00:54.790,710] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:54.797,912] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:54.806,121] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:54.814,483] bt_hci_core: hci_event: event 0x3e [00:00:54.820,739] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:54.828,002] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:54.838,836] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:54.850,524] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:54.857,727] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:54.865,936] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 [00:00:54.874,298] bt_hci_core: hci_event: event 0x05 [00:00:54.880,554] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:54.889,862] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:54.896,850] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:54.905,700] bt_conn: tx_notify: conn 0x20000f00 [00:00:54.912,078] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:54.919,281] bt_hci_core: process_events: count 2 [00:00:54.925,689] bt_hci_core: process_events: ev->state 0 [00:00:54.932,464] bt_hci_core: process_events: ev->state 1 [00:00:54.939,270] bt_conn: bt_conn_prepare_events: [00:00:54.945,434] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:54.953,674] bt_conn: deferred_work: conn 0x20000f00 [00:00:54.960,388] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:54.969,024] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:54.977,081] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:54.985,717] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:54.994,323] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:55.002,502] bt_att: att_chan_detach: chan 0x20003d88 [00:00:55.009,307] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:55.016,662] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:55.028,442] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:55.035,583] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:55.042,816] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:55.051,239] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:55.058,135] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:55.066,436] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:55.073,822] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:55.083,282] bt_hci_core: process_events: count 2 [00:00:55.089,721] bt_hci_core: process_events: ev->state 4 [00:00:55.096,496] bt_hci_core: send_cmd: calling net_buf_get [00:00:55.103,454] bt_hci_core: send_cmd: calling sem_take_wait [00:00:55.110,595] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:55.120,300] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:55.127,929] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:55.136,260] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:55.153,686] bt_hci_core: process_events: ev->state 0 [00:00:55.160,461] bt_conn: bt_conn_prepare_events: [00:00:55.166,625] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:55.174,865] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:55.181,915] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:55.198,547] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:55.205,261] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:55.213,409] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:55.220,397] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:55.227,539] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:55.236,846] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:55.246,276] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:55.253,356] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |. [00:00:55.475,219] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 1f 0a 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 |.>...... ....p.z. 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00 01 |.. [00:00:55.511,901] bt_hci_driver: bt_rpmsg_evt_recv: len 31 [00:00:55.518,737] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) [00:00:55.526,885] bt_hci_core: bt_recv: buf 0x20006b70 len 33 [00:00:55.533,996] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:55.542,175] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 [00:00:55.550,628] bt_hci_core: hci_event: event 0x3e [00:00:55.556,884] bt_hci_core: hci_le_meta_event: subevent 0x0a [00:00:55.564,300] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 7A:0E:70:1B:0E:9F (random) peer RPA 00:00:00:00:00:00 [00:00:55.579,162] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 [00:00:55.588,562] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:55.595,458] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:55.602,539] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:55.609,436] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:55.616,638] bt_conn: bt_conn_set_state: connecting-adv -> connected [00:00:55.624,816] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 [00:00:55.632,446] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 [00:00:55.641,052] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 [00:00:55.648,864] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 [00:00:55.656,402] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 [00:00:55.665,008] bt_att: bt_att_accept: conn 0x20000f00 handle 15 [00:00:55.672,576] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 [00:00:55.681,457] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c [00:00:55.690,093] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 [00:00:55.697,998] bt_gatt: bt_gatt_connected: conn 0x20000f00 [00:00:55.705,108] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c Connected 7A:0E:70:1B:0E:9F (random) [00:00:55.716,857] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 [00:00:55.725,189] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:55.732,543] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 [00:00:55.742,004] bt_hci_core: process_events: count 2 [00:00:55.748,443] bt_hci_core: process_events: ev->state 4 [00:00:55.755,218] bt_hci_core: send_cmd: calling net_buf_get [00:00:55.762,176] bt_hci_core: send_cmd: calling sem_take_wait [00:00:55.769,317] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver [00:00:55.778,991] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 [00:00:55.786,621] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 [00:00:55.794,982] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 16 20 02 0f 00 |.. ... [00:00:55.812,316] bt_hci_core: process_events: ev->state 1 [00:00:55.819,122] bt_conn: bt_conn_prepare_events: [00:00:55.825,256] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list [00:00:55.834,320] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events [00:00:55.842,559] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00 0f 00 01 01 9f 0e 1b 70 0e 7a 00 00 00 00 00 |........ p.z..... 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. [00:00:55.869,720] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 3e 06 12 00 00 0f 00 00 |.>...... . [00:00:55.886,383] bt_hci_driver: bt_rpmsg_evt_recv: len 6 [00:00:55.893,096] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) [00:00:55.901,245] bt_hci_core: bt_recv: buf 0x20006b8c len 8 [00:00:55.908,264] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 3e 06 12 00 00 0f 00 00 |>....... [00:00:55.925,262] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 05 04 00 0f 00 3e |......> [00:00:55.941,894] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:55.948,608] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) [00:00:55.956,756] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 [00:00:55.963,775] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e [00:00:55.973,541] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 [00:00:55.980,529] bt_conn: bt_conn_set_state: connected -> disconnect-complete [00:00:55.989,105] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 [00:00:55.996,276] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 05 04 00 0f 00 3e |.....> [00:00:56.013,244] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 16 20 |...... [00:00:56.029,846] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:56.036,560] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:56.044,677] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:56.051,696] bt_hci_core: hci_cmd_status: opcode 0x2016 [00:00:56.058,654] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 [00:00:56.067,932] bt_hci_core: opcode 0x2016 status 0x02 [00:00:56.074,829] bt_hci_core: Failed read remote features (-5) [00:00:56.082,305] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 [00:00:56.090,637] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c [00:00:56.097,991] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 [00:00:56.107,543] bt_hci_core: process_events: count 3 [00:00:56.113,952] bt_hci_core: process_events: ev->state 4 [00:00:56.120,758] bt_hci_core: send_cmd: calling net_buf_get [00:00:56.127,685] bt_hci_core: send_cmd: calling sem_take_wait [00:00:56.134,857] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver [00:00:56.144,561] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 [00:00:56.152,282] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 [00:00:56.160,705] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... [00:00:56.178,131] bt_hci_core: process_events: ev->state 0 [00:00:56.184,906] bt_hci_core: process_events: ev->state 0 [00:00:56.191,711] bt_conn: bt_conn_prepare_events: [00:00:56.197,845] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:56.206,085] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:56.213,165] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0f 04 02 01 32 20 |.....2 [00:00:56.229,797] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:56.236,480] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) [00:00:56.244,628] bt_hci_core: bt_recv: buf 0x20006f6c len 6 [00:00:56.251,617] bt_hci_core: hci_cmd_status: opcode 0x2032 [00:00:56.258,605] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c [00:00:56.267,883] bt_hci_core: opcode 0x2032 status 0x02 [00:00:56.274,780] bt_hci_core: Failed LE Set PHY (-5) [00:00:56.281,372] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:56.288,574] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:56.296,783] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 [00:00:56.305,114] bt_hci_core: hci_event: event 0x3e [00:00:56.311,370] bt_hci_core: hci_le_meta_event: subevent 0x12 [00:00:56.318,634] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 [00:00:56.329,467] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 Advertiser[0] 0x20000d28 connected conn 0x20000f00 [00:00:56.341,156] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:56.348,358] bt_hci_core: rx_work_handler: Getting net_buf from queue [00:00:56.356,567] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 [00:00:56.364,898] bt_hci_core: hci_event: event 0x05 [00:00:56.371,154] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e [00:00:56.380,493] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 [00:00:56.387,451] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected [00:00:56.396,301] bt_conn: tx_notify: conn 0x20000f00 [00:00:56.402,679] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 [00:00:56.409,881] bt_hci_core: process_events: count 2 [00:00:56.416,320] bt_hci_core: process_events: ev->state 0 [00:00:56.423,095] bt_hci_core: process_events: ev->state 1 [00:00:56.429,870] bt_conn: bt_conn_prepare_events: [00:00:56.436,035] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:56.444,274] bt_conn: deferred_work: conn 0x20000f00 [00:00:56.450,988] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 [00:00:56.459,625] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 [00:00:56.467,712] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 [00:00:56.476,318] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c [00:00:56.484,954] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 [00:00:56.493,103] bt_att: att_chan_detach: chan 0x20003d88 [00:00:56.499,938] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 [00:00:56.507,293] bt_att: bt_att_released: chan 0x20003d88 Disconnected: 7A:0E:70:1B:0E:9F (random) (reason 62) [00:00:56.519,073] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 [00:00:56.526,245] bt_adv: bt_le_adv_resume: No valid legacy adv [00:00:56.533,477] bt_conn: bt_conn_set_state: disconnected -> connecting-adv [00:00:56.541,870] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 [00:00:56.548,767] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 [00:00:56.557,098] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 [00:00:56.564,453] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 [00:00:56.573,944] bt_hci_core: process_events: count 2 [00:00:56.580,383] bt_hci_core: process_events: ev->state 4 [00:00:56.587,158] bt_hci_core: send_cmd: calling net_buf_get [00:00:56.594,116] bt_hci_core: send_cmd: calling sem_take_wait [00:00:56.601,287] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver [00:00:56.610,961] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 [00:00:56.618,591] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 [00:00:56.626,922] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. [00:00:56.644,348] bt_hci_core: process_events: ev->state 0 [00:00:56.651,123] bt_conn: bt_conn_prepare_events: [00:00:56.657,287] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events [00:00:56.665,527] bt_hci_driver: bt_rpmsg_rx: RX buf payload: [00:00:56.672,607] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 04 0e 04 01 39 20 00 |....9 . [00:00:56.689,208] bt_hci_driver: bt_rpmsg_evt_recv: len 4 [00:00:56.695,922] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) [00:00:56.704,071] bt_hci_core: bt_recv: buf 0x20006f50 len 6 [00:00:56.711,059] bt_hci_core: hci_cmd_complete: opcode 0x2039 [00:00:56.718,231] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 [00:00:56.727,508] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 [00:00:56.736,938] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 [00:00:56.744,018] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 94 |.