00> [00:00:00.453,155] bt_hci_core: bt_hci_driver_register: Registered RPMsg 00> *** Booting Zephyr OS build v3.1.99-ncs1-10-gd4b8ac4db1ed *** 00> 00> Starting Bluetooth multiple advertising sets example 00> 00> [00:00:00.471,710] bt_hci_core: hci_tx_thread: Started 00> [00:00:00.478,027] bt_conn: bt_conn_prepare_events: 00> [00:00:00.484,161] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:00.492,431] bt_hci_driver: bt_rpmsg_open: 00> [00:00:00.499,359] bt_hci_core: bt_hci_cmd_create: opcode 0x0c03 param_len 0 00> [00:00:00.507,690] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:00.515,045] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x0c03 len 3 00> [00:00:00.524,475] bt_hci_core: process_events: count 2 00> [00:00:00.530,914] bt_hci_core: process_events: ev->state 4 00> [00:00:00.537,658] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:00.544,616] [00:00:11.812,927] bt_hci_core: hci_cmd_complete: opcode 0x1009 00> [00:00:11.820,098] bt_hci_core: hci_cmd_done: opcode 0x1009 status 0x00 buf 0x20006f50 00> [00:00:11.829,376] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 00 00 00 00 00 00 |....... 00> [00:00:11.846,374] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x1009 len 7 00> [00:00:11.855,834] bt_id: bt_id_read_public_addr: Controller has no public address 00> [00:00:11.864,685] bt_id: bt_id_init: No public address. Trying to set static random. 00> [00:00:11.873,779] bt_hci_core: bt_hci_cmd_create: opcode 0xfc09 param_len 0 00> [00:00:11.882,110] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:11.889,465] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0xfc09 len 3 00> [00:00:11.898,925] bt_hci_core: process_events: count 2 00> [00:00:11.905,364] bt_hci_core: process_events: ev->state 4 00> [00:00:11.912,139] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:11.919,097] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:11.926,239] bt_hci_core: send_cmd: Sending command 0xfc09 (buf 0x20006f50) to driver 00> [00:00:11.935,913] bt_hci_core: bt_send: buf 0x20006f50 len 3 type 0 00> [00:00:11.943,572] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 3 00> [00:00:11.951,904] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 09 fc 00 |.... 00> [00:00:11.969,238] bt_hci_core: process_events: ev->state 0 00> [00:00:11.976,013] bt_conn: bt_conn_prepare_events: 00> [00:00:11.982,177] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:11.990,447] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 1b 01 09 fc 00 01 e1 62 e1 fa e5 ef fa f3 |........ .b...... 00> c6 bc 9a 47 73 9d 66 61 97 03 a8 b6 7e 77 |...Gs.fa ....~w 00> [00:00:12.017,303] bt_hci_driver: bt_rpmsg_evt_recv: len 27 00> [00:00:12.024,108] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:12.032,257] bt_hci_core: bt_recv: buf 0x20006f50 len 29 00> [00:00:12.039,337] bt_hci_core: hci_cmd_complete: opcode 0xfc09 00> [00:00:12.046,508] bt_hci_core: hci_cmd_done: opcode 0xfc09 status 0x00 buf 0x20006f50 00> [00:00:12.055,786] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 01 e1 62 e1 fa e5 ef fa f3 c6 bc 9a 47 73 9d |...b.... .....Gs. 00> 66 61 97 03 a8 b6 7e 77 |fa....~w 00> [00:00:12.082,946] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0xfc09 len 24 00> [00:00:12.092,590] bt_id: set_random_address: EF:E5:FA:E1:62:E1 00> [00:00:12.099,731] bt_hci_core: bt_hci_cmd_create: opcode 0x2005 param_len 6 00> [00:00:12.108,062] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:12.115,417] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2005 len 9 00> [00:00:12.124,877] bt_hci_core: process_events: count 2 00> [00:00:12.131,286] bt_hci_core: process_events: ev->state 4 00> [00:00:12.138,092] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:12.145,050] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:12.152,191] bt_hci_core: send_cmd: Sending command 0x2005 (buf 0x20006f50) to driver 00> [00:00:12.161,865] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:12.169,494] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:12.177,856] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 05 20 06 e1 62 e1 fa e5 ef |.. ..b.. .. 00> [00:00:12.195,281] bt_hci_core: process_events: ev->state 0 00> [00:00:12.202,087] bt_conn: bt_conn_prepare_events: 00> [00:00:12.208,221] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:12.216,522] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 05 20 00 |..... . 00> [00:00:12.233,154] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:12.239,868] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:12.248,016] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:12.255,004] bt_hci_core: hci_cmd_complete: opcode 0x2005 00> [00:00:12.262,176] bt_hci_core: hci_cmd_done: opcode 0x2005 status 0x00 buf 0x20006f50 00> [00:00:12.271,453] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 |. 00> [00:00:12.288,360] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2005 len 1 00> [00:00:12.297,973] bt_hci_core: Identity: EF:E5:FA:E1:62:E1 (random) 00> [00:00:12.305,603] bt_hci_core: HCI: version 5.3 (0x0c) revision 0x21d8, manufacturer 0x0059 00> [00:00:12.315,429] bt_hci_core: LMP: version 5.3 (0x0c) subver 0x21d8 00> Bluetooth initialized 00> 00> [00:00:12.325,134] bt_hci_core: bt_hci_cmd_create: opcode 0x2036 param_len 25 00> [00:00:12.333,587] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:12.341,033] bt_id: bt_id_set_adv_random_addr: EF:E5:FA:E1:62:E1 00> [00:00:12.348,815] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2036 len 28 00> [00:00:12.358,367] bt_hci_core: process_events: count 2 00> [00:00:12.364,776] bt_hci_core: process_events: ev->state 4 00> [00:00:12.371,582] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:12.378,540] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:12.385,681] bt_hci_core: send_cmd: Sending command 0x2036 (buf 0x20006f50) to driver 00> [00:00:12.395,385] bt_hci_core: bt_send: buf 0x20006f50 len 28 type 0 00> [00:00:12.403,106] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 28 00> [00:00:12.411,560] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 36 20 19 00 01 00 a0 00 00 f0 00 00 07 01 00 |.6 ..... ........ 00> 00 00 00 00 00 00 00 7f 01 00 02 00 00 |........ ..... 00> [00:00:12.439,086] bt_hci_core: process_events: ev->state 0 00> [00:00:12.445,861] bt_conn: bt_conn_prepare_events: 00> [00:00:12.452,026] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:12.460,296] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 05 01 36 20 00 00 |....6 .. 00> [00:00:12.476,928] bt_hci_driver: bt_rpmsg_evt_recv: len 5 00> [00:00:12.483,642] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:12.491,790] bt_hci_core: bt_recv: buf 0x20006f50 len 7 00> [00:00:12.498,779] bt_hci_core: hci_cmd_complete: opcode 0x2036 00> [00:00:12.505,950] bt_hci_core: hci_cmd_done: opcode 0x2036 status 0x00 buf 0x20006f50 00> [00:00:12.515,228] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 00 |.. 00> [00:00:12.532,165] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2036 len 2 00> [00:00:12.541,717] bt_id: bt_id_set_adv_random_addr: EF:E5:FA:E1:62:E1 00> [00:00:12.549,468] bt_hci_core: bt_hci_cmd_create: opcode 0x2035 param_len 7 00> [00:00:12.557,800] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:12.565,155] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2035 len 10 00> [00:00:12.574,707] bt_hci_core: process_events: count 2 00> [00:00:12.581,146] bt_hci_core: process_events: ev->state 4 00> [00:00:12.587,921] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:12.594,879] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:12.602,020] bt_hci_core: send_cmd: Sending command 0x2035 (buf 0x20006f50) to driver 00> [00:00:12.611,724] bt_hci_core: bt_send: buf 0x20006f50 len 10 type 0 00> [00:00:12.619,445] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 10 00> [00:00:12.627,899] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 35 20 07 00 e1 62 e1 fa e5 ef |.5 ...b. ... 00> [00:00:12.645,355] bt_hci_core: process_events: ev->state 0 00> [00:00:12.652,130] bt_conn: bt_conn_prepare_events: 00> [00:00:12.658,294] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:12.666,564] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 35 20 00 |....5 . 00> [00:00:12.683,197] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:12.689,910] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:12.698,059] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:12.705,047] bt_hci_core: hci_cmd_complete: opcode 0x2035 00> [00:00:12.712,219] bt_hci_core: hci_cmd_done: opcode 0x2035 status 0x00 buf 0x20006f50 00> [00:00:12.721,496] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 |. 00> [00:00:12.738,403] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2035 len 1 00> Created adv: 0x20000d28 00> 00> [00:00:12.750,091] bt_hci_core: bt_hci_cmd_create: opcode 0x2037 param_len 34 00> [00:00:12.758,514] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:12.765,869] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2037 len 37 00> [00:00:12.775,421] bt_hci_core: process_events: count 2 00> [00:00:12.781,860] bt_hci_core: process_events: ev->state 4 00> [00:00:12.788,635] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:12.795,593] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:12.802,764] bt_hci_core: send_cmd: Sending command 0x2037 (buf 0x20006f50) to driver 00> [00:00:12.812,438] bt_hci_core: bt_send: buf 0x20006f50 len 37 type 0 00> [00:00:12.820,190] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 37 00> [00:00:12.828,613] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 37 20 22 00 03 01 1e 02 01 06 03 03 0a 18 16 |.7 ".... ........ 00> 09 4e 6f 72 64 69 63 20 6d 75 6c 74 69 20 61 64 |.Nordic multi ad 00> 76 20 73 65 74 73 |v sets 00> [00:00:12.866,180] bt_hci_core: process_events: ev->state 0 00> [00:00:12.872,985] bt_conn: bt_conn_prepare_events: 00> [00:00:12.879,150] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:12.887,420] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 37 20 00 |....7 . 00> [00:00:12.904,052] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:12.910,766] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:12.918,914] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:12.925,903] bt_hci_core: hci_cmd_complete: opcode 0x2037 00> [00:00:12.933,074] bt_hci_core: hci_cmd_done: opcode 0x2037 status 0x00 buf 0x20006f50 00> [00:00:12.942,352] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 |. 00> [00:00:12.959,289] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2037 len 1 00> [00:00:12.968,719] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:12.977,142] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:12.984,039] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:12.992,370] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:12.999,725] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:13.009,185] bt_hci_core: process_events: count 2 00> [00:00:13.015,625] bt_hci_core: process_events: ev->state 4 00> [00:00:13.022,399] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:13.029,357] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:13.036,499] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:13.046,173] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:13.053,833] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:13.062,164] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:13.079,589] bt_hci_core: process_events: ev->state 0 00> [00:00:13.086,364] bt_conn: bt_conn_prepare_events: 00> [00:00:13.092,529] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:13.100,799] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:13.117,431] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:13.124,145] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:13.132,293] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:13.139,282] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:13.146,453] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:13.155,731] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 |. 00> [00:00:13.172,668] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:13.182,098] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> Connectable advertising started 00> 00> [00:00:13.192,352] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2 00> [00:00:13.200,866] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.209,533] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.218,200] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.226,745] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2 00> [00:00:13.235,260] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.243,927] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.252,624] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.261,138] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 2 00> [00:00:13.269,805] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.278,350] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.287,017] bt_gatt: bt_gatt_attr_read: handle 0x0000 offset 0 length 5 00> [00:00:13.295,684] bt_gatt: db_hash_gen: Hash: 00> 24 67 64 17 46 07 e9 4e 8c f2 1c ce d4 b1 70 f2 |$gd.F..N ......p. 00> [00:00:28.659,057] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:28.695,770] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:28.702,575] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:28.710,723] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:28.717,864] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:28.726,074] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:28.734,497] bt_hci_core: hci_event: event 0x3e 00> [00:00:28.740,753] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:28.748,199] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:28.763,061] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:28.772,460] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:28.779,357] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:28.786,437] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:28.793,334] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:28.800,537] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:28.808,715] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:28.816,345] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:28.824,981] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:28.832,763] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:28.840,301] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:28.848,937] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:28.856,475] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:28.865,386] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:28.874,023] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:28.881,896] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:28.889,007] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:28.900,787] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:28.909,118] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:28.916,473] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:28.925,933] bt_hci_core: process_events: count 2 00> [00:00:28.932,342] bt_hci_core: process_events: ev->state 4 00> [00:00:28.939,147] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:28.946,105] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:28.953,247] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:28.962,951] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:28.970,581] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:28.978,912] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:28.996,276] bt_hci_core: process_events: ev->state 1 00> [00:00:29.003,082] bt_conn: bt_conn_prepare_events: 00> [00:00:29.009,216] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:29.018,249] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:29.026,489] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:29.053,710] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:29.070,343] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:29.077,087] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:29.085,235] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:29.092,224] [0m bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:29.109,252] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:29.125,854] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:29.132,568] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:29.140,716] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:29.147,735] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:29.157,501] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:29.164,489] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:29.173,065] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:29.180,236] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:29.197,265] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:29.213,867] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:29.220,581] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:29.228,729] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:29.235,717] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:29.242,706] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:29.251,983] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:29.258,880] bt_hci_core: Failed read remote features (-5) 00> [00:00:29.266,387] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:29.274,719] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:29.282,073] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:29.291,625] bt_hci_core: process_events: count 3 00> [00:00:29.298,065] bt_hci_core: process_events: ev->state 4 00> [00:00:29.304,840] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:29.311,798] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:29.318,969] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:29.328,674] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:29.336,395] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:29.344,818] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:29.362,274] bt_hci_core: process_events: ev->state 0 00> [00:00:29.369,049] bt_hci_core: process_events: ev->state 0 00> [00:00:29.375,823] bt_conn: bt_conn_prepare_events: 00> [00:00:29.381,988] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:29.390,228] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:29.397,308] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:29.413,940] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:29.420,623] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:29.428,771] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:29.435,791] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:29.442,779] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:29.452,056] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:29.458,953] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:29.465,545] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:29.472,747] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:29.480,957] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:29.489,318] bt_hci_core: hci_event: event 0x3e 00> [00:00:29.495,574] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:29.502,807] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:29.513,671] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:29.525,360] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:29.532,562] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:29.540,771] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:29.549,133] bt_hci_core: hci_event: event 0x05 00> [00:00:29.555,389] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:29.564,697] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:29.571,685] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:29.580,535] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:29.586,914] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:29.594,116] bt_hci_core: process_events: count 2 00> [00:00:29.600,555] bt_hci_core: process_events: ev->state 0 00> [00:00:29.607,330] bt_hci_core: process_events: ev->state 1 00> [00:00:29.614,105] bt_conn: bt_conn_prepare_events: 00> [00:00:29.620,269] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:29.628,540] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:29.635,253] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:29.643,890] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:29.651,947] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:29.660,583] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:29.669,189] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:29.677,368] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:29.684,173] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:29.691,558] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:29.703,338] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:29.710,510] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:29.717,742] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:29.726,165] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:29.733,062] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:29.741,394] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:29.748,748] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:29.758,239] bt_hci_core: process_events: count 2 00> [00:00:29.764,678] bt_hci_core: process_events: ev->state 4 00> [00:00:29.771,453] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:29.778,411] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:29.785,583] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:29.795,288] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:29.802,917] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:29.811,248] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:29.828,674] bt_hci_core: process_events: ev->state 0 00> [00:00:29.835,449] bt_conn: bt_conn_prepare_events: 00> [00:00:29.841,613] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:29.849,853] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:29.856,933] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:29.873,535] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:29.880,249] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:29.888,397] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:29.895,385] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:29.902,557] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:29.911,834] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:29.921,264] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:29.928,375] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:29.945,281] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:29.981,964] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:29.988,769] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:29.996,917] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 00> [00:00:30.004,028] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:30.012,237] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 00> [00:00:30.020,690] bt_hci_core: hci_event: event 0x3e 00> [00:00:30.026,916] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:30.034,362] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:30.049,224] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:30.058,624] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:30.065,521] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:30.072,601] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:30.079,498] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:30.086,700] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:30.094,879] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:30.102,508] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:30.111,145] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:30.118,957] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:30.126,464] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:30.135,101] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:30.142,669] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:30.151,550] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:30.160,186] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:30.168,090] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:30.175,201] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:30.186,981] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:30.195,312] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:30.202,667] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:30.212,127] bt_hci_core: process_events: count 2 00> [00:00:30.218,566] bt_hci_core: process_events: ev->state 4 00> [00:00:30.225,341] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:30.232,299] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:30.239,440] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:30.249,145] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:30.256,774] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:30.265,106] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:30.282,470] bt_hci_core: process_events: ev->state 1 00> [00:00:30.289,276] bt_conn: bt_conn_prepare_events: 00> [00:00:30.295,410] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:30.304,473] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:30.312,713] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:30.339,904] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:30.356,536] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:30.363,281] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:30.371,429] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:30.378,417] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:30.395,446] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:30.412,048] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:30.418,792] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:30.426,910] bt_hci_core: bt_recv: buf 0x20006b70 len 6 00> [00:00:30.433,929] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:30.443,695] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:30.450,683] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:30.459,259] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:30.466,430] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:30.483,428] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:30.500,061] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:30.506,744] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:30.514,892] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:30.521,881] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:30.528,869] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:30.538,146] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:30.545,043] bt_hci_core: Failed read remote features (-5) 00> [00:00:30.552,551] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:30.560,882] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:30.568,237] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:30.577,789] bt_hci_core: process_events: count 3 00> [00:00:30.584,228] bt_hci_core: process_events: ev->state 4 00> [00:00:30.591,003] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:30.597,961] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:30.605,133] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:30.614,807] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:30.622,528] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:30.630,981] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:30.648,406] bt_hci_core: process_events: ev->state 0 00> [00:00:30.655,181] bt_hci_core: process_events: ev->state 0 00> [00:00:30.661,987] bt_conn: bt_conn_prepare_events: 00> [00:00:30.668,121] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:30.676,391] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:30.683,441] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:30.700,073] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:30.706,756] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:30.714,904] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:30.721,893] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:30.728,881] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:30.738,159] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:30.745,025] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:30.751,647] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:30.758,819] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:30.767,028] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:30.775,390] bt_hci_core: hci_event: event 0x3e 00> [00:00:30.781,616] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:30.788,879] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:30.799,743] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:30.811,431] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:30.818,634] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:30.826,843] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 00> [00:00:30.835,174] bt_hci_core: hci_event: event 0x05 00> [00:00:30.841,430] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:30.850,769] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:30.857,757] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:30.866,577] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:30.872,985] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:30.880,157] bt_hci_core: process_events: count 2 00> [00:00:30.886,596] bt_hci_core: process_events: ev->state 0 00> [00:00:30.893,371] bt_hci_core: process_events: ev->state 1 00> [00:00:30.900,146] bt_conn: bt_conn_prepare_events: 00> [00:00:30.906,341] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:30.914,581] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:30.921,295] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:30.929,931] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:30.937,988] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:30.946,624] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:30.955,261] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:30.963,409] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:30.970,245] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:30.977,630] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:30.989,410] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:30.996,582] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:31.003,814] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:31.012,207] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:31.019,104] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:31.027,435] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:31.034,790] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:31.044,281] bt_hci_core: process_events: count 2 00> [00:00:31.050,720] bt_hci_core: process_events: ev->state 4 00> [00:00:31.057,495] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:31.064,453] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:31.071,624] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:31.081,298] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:31.088,958] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:31.097,290] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:31.114,715] bt_hci_core: process_events: ev->state 0 00> [00:00:31.121,490] bt_conn: bt_conn_prepare_events: 00> [00:00:31.127,655] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:31.135,894] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:31.142,974] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:31.159,606] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:31.166,290] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:31.174,438] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:31.181,427] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:31.188,598] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:31.197,875] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:31.207,336] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:31.214,447] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:31.231,353] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:31.268,066] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:31.274,871] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:31.283,020] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:31.290,130] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:31.298,339] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:31.306,793] bt_hci_core: hci_event: event 0x3e 00> [00:00:31.313,049] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:31.320,495] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:31.335,357] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:31.344,726] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:31.351,654] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:31.358,734] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:31.365,631] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:31.372,833] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:31.381,011] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:31.388,641] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:31.397,277] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:31.405,090] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:31.412,628] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:31.421,264] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:31.428,802] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:31.437,713] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:31.446,350] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:31.454,254] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:31.461,364] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:31.473,144] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:31.481,475] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:31.488,830] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:31.498,291] bt_hci_core: process_events: count 2 00> [00:00:31.504,730] bt_hci_core: process_events: ev->state 4 00> [00:00:31.511,535] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:31.518,493] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:31.525,634] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:31.535,308] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:31.542,968] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:31.551,300] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:31.568,664] bt_hci_core: process_events: ev->state 1 00> [00:00:31.575,469] bt_conn: bt_conn_prepare_events: 00> [00:00:31.581,604] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:31.590,667] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:31.598,907] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:31.626,098] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:31.642,761] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:31.649,475] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:31.657,623] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:31.664,642] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:31.681,640] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:31.698,272] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:31.704,986] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:31.713,165] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:31.720,153] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:31.729,949] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:31.736,907] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:31.745,513] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:31.752,685] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:31.769,653] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:31.786,285] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:31.792,999] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:31.801,147] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:31.808,135] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:31.815,124] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:31.824,401] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:31.831,298] bt_hci_core: Failed read remote features (-5) 00> [00:00:31.838,806] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:31.847,137] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:31.854,492] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:31.864,074] bt_hci_core: process_events: count 3 00> [00:00:31.870,483] bt_hci_core: process_events: ev->state 4 00> [00:00:31.877,288] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:31.884,216] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:31.891,418] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:31.901,092] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:31.908,813] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:31.917,266] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:31.934,692] bt_hci_core: process_events: ev->state 0 00> [00:00:31.941,467] bt_hci_core: process_events: ev->state 0 00> [00:00:31.948,272] bt_conn: bt_conn_prepare_events: 00> [00:00:31.954,437] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:31.962,677] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:31.969,757] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:31.986,358] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:31.993,072] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:32.001,220] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:32.008,209] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:32.015,197] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:32.024,475] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:32.031,341] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:32.037,963] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:32.045,135] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:32.053,375] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:32.061,706] bt_hci_core: hci_event: event 0x3e 00> [00:00:32.067,962] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:32.075,195] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:32.086,059] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:32.097,717] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:32.104,919] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:32.113,128] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:32.121,490] bt_hci_core: hci_event: event 0x05 00> [00:00:32.127,746] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:32.137,084] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:32.144,073] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:32.152,893] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:32.159,301] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:32.166,503] bt_hci_core: process_events: count 2 00> [00:00:32.172,912] bt_hci_core: process_events: ev->state 0 00> [00:00:32.179,718] bt_hci_core: process_events: ev->state 1 00> [00:00:32.186,492] bt_conn: bt_conn_prepare_events: 00> [00:00:32.192,657] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:32.200,927] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:32.207,641] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:32.216,278] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:32.224,365] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:32.232,971] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:32.241,607] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:32.249,786] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:32.256,591] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:32.263,977] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:32.275,756] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:32.282,928] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:32.290,161] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:32.298,583] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:32.305,480] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:32.313,812] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:32.321,197] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:32.330,688] bt_hci_core: process_events: count 2 00> [00:00:32.337,097] bt_hci_core: process_events: ev->state 4 00> [00:00:32.343,902] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:32.350,860] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:32.358,032] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:32.367,736] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:32.375,366] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:32.383,697] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:32.401,123] bt_hci_core: process_events: ev->state 0 00> [00:00:32.407,928] bt_conn: bt_conn_prepare_events: 00> [00:00:32.414,093] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:32.422,332] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:32.429,412] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:32.446,044] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:32.452,728] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:32.460,906] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:32.467,895] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:32.475,067] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:32.484,344] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:32.493,774] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:32.500,885] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:32.517,822] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:32.554,504] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:32.561,340] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:32.569,488] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 00> [00:00:32.576,599] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:32.584,808] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 00> [00:00:32.593,231] bt_hci_core: hci_event: event 0x3e 00> [00:00:32.599,487] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:32.606,933] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:32.621,795] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:32.631,195] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:32.638,122] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:32.645,172] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:32.652,099] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:32.659,301] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:32.667,480] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:32.675,109] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:32.683,746] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:32.691,558] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:32.699,096] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:32.707,733] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:32.715,270] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:32.724,182] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:32.732,818] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:32.740,722] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:32.747,833] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:32.759,613] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:32.767,944] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:32.775,299] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:32.784,759] bt_hci_core: process_events: count 2 00> [00:00:32.791,198] bt_hci_core: process_events: ev->state 4 00> [00:00:32.797,973] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:32.804,931] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:32.812,072] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:32.821,777] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:32.829,406] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:32.837,768] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:32.855,102] bt_hci_core: process_events: ev->state 1 00> [00:00:32.861,907] bt_conn: bt_conn_prepare_events: 00> [00:00:32.868,041] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:32.877,105] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:32.885,345] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:32.912,536] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:32.929,199] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:32.935,913] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:32.944,061] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:32.951,080] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:32.968,078] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:32.984,710] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:32.991,424] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:32.999,572] bt_hci_core: bt_recv: buf 0x20006b70 len 6 00> [00:00:33.006,591] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:33.016,357] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:33.023,345] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:33.031,921] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:33.039,093] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:33.056,060] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:33.072,692] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:33.079,376] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:33.087,524] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:33.094,543] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:33.101,501] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:33.110,809] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:33.117,675] bt_hci_core: Failed read remote features (-5) 00> [00:00:33.125,183] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:33.133,514] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:33.140,869] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:33.150,451] bt_hci_core: process_events: count 3 00> [00:00:33.156,890] bt_hci_core: process_events: ev->state 4 00> [00:00:33.163,665] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:33.170,623] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:33.177,795] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:33.187,500] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:33.195,220] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:33.203,674] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:33.221,099] bt_hci_core: process_events: ev->state 0 00> [00:00:33.227,874] bt_hci_core: process_events: ev->state 0 00> [00:00:33.234,649] bt_conn: bt_conn_prepare_events: 00> [00:00:33.240,814] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:33.249,053] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:33.256,134] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:33.272,766] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:33.279,449] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:33.287,597] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:33.294,616] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:33.301,605] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:33.310,882] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:33.317,779] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:33.324,371] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:33.331,573] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:33.339,782] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:33.348,144] bt_hci_core: hci_event: event 0x3e 00> [00:00:33.354,400] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:33.361,663] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:33.372,497] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:33.384,216] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:33.391,418] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:33.399,627] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 00> [00:00:33.407,958] bt_hci_core: hci_event: event 0x05 00> [00:00:33.414,215] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:33.423,553] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:33.430,541] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:33.439,392] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:33.445,770] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:33.452,972] bt_hci_core: process_events: count 2 00> [00:00:33.459,411] bt_hci_core: process_events: ev->state 0 00> [00:00:33.466,186] bt_hci_core: process_events: ev->state 1 00> [00:00:33.472,961] bt_conn: bt_conn_prepare_events: 00> [00:00:33.479,156] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:33.487,396] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:33.494,110] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:33.502,746] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:33.510,833] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:33.519,439] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:33.528,076] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:33.536,254] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:33.543,060] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:33.550,445] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:33.562,225] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:33.569,396] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:33.576,629] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:33.585,021] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:33.591,918] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:33.600,250] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:33.607,604] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:33.617,095] bt_hci_core: process_events: count 2 00> [00:00:33.623,535] bt_hci_core: process_events: ev->state 4 00> [00:00:33.630,310] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:33.637,268] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:33.644,439] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:33.654,144] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:33.661,773] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:33.670,104] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:33.687,530] bt_hci_core: process_events: ev->state 0 00> [00:00:33.694,305] bt_conn: bt_conn_prepare_events: 00> [00:00:33.700,469] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:33.708,709] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:33.715,789] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:33.732,421] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:33.739,105] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:33.747,283] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:33.754,272] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:33.761,444] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:33.770,721] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:33.780,151] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:33.787,231] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:33.804,473] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:33.841,186] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:33.847,991] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:33.856,140] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:33.863,250] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:33.871,429] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:33.879,882] bt_hci_core: hci_event: event 0x3e 00> [00:00:33.886,138] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:33.893,585] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:33.908,447] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:33.917,816] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:33.924,713] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:33.931,793] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:33.938,690] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:33.945,922] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:33.954,071] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:33.961,700] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:33.970,336] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:33.978,149] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:33.985,656] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:33.994,293] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:34.001,861] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:34.010,772] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:34.019,378] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:34.027,282] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:34.034,393] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:34.046,142] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:34.054,473] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:34.061,828] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:34.071,289] bt_hci_core: process_events: count 2 00> [00:00:34.077,728] bt_hci_core: process_events: ev->state 4 00> [00:00:34.084,503] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:34.091,461] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:34.098,602] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:34.108,306] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:34.115,936] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:34.124,267] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:34.141,632] bt_hci_core: process_events: ev->state 1 00> [00:00:34.148,406] bt_conn: bt_conn_prepare_events: 00> [00:00:34.154,571] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:34.163,635] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:34.171,844] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:34.199,035] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:34.215,698] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:34.222,412] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:34.230,560] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:34.237,579] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:34.254,577] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:34.271,209] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:34.277,923] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:34.286,071] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:34.293,090] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:34.302,856] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:34.309,844] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:34.318,450] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:34.325,592] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:34.342,590] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:34.359,222] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:34.365,936] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:34.374,084] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:34.381,072] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:34.388,061] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:34.397,338] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:34.404,235] bt_hci_core: Failed read remote features (-5) 00> [00:00:34.411,743] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:34.420,074] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:34.427,429] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:34.436,981] bt_hci_core: process_events: count 3 00> [00:00:34.443,420] bt_hci_core: process_events: ev->state 4 00> [00:00:34.450,195] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:34.457,153] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:34.464,324] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:34.474,029] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:34.481,750] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:34.490,173] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:34.507,629] bt_hci_core: process_events: ev->state 0 00> [00:00:34.514,404] bt_hci_core: process_events: ev->state 0 00> [00:00:34.521,179] bt_conn: bt_conn_prepare_events: 00> [00:00:34.527,343] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:34.535,583] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:34.542,663] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:34.559,295] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:34.565,979] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:34.574,157] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:34.581,146] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:34.588,134] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:34.597,412] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:34.604,278] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:34.610,900] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:34.618,072] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:34.626,281] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:34.634,643] bt_hci_core: hci_event: event 0x3e 00> [00:00:34.640,899] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:34.648,132] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:34.658,996] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:34.670,684] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:34.677,886] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:34.686,096] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:34.694,427] bt_hci_core: hci_event: event 0x05 00> [00:00:34.700,683] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:34.709,991] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:34.716,979] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:34.725,830] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:34.732,208] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:34.739,410] bt_hci_core: process_events: count 2 00> [00:00:34.745,819] bt_hci_core: process_events: ev->state 0 00> [00:00:34.752,593] bt_hci_core: process_events: ev->state 1 00> [00:00:34.759,368] bt_conn: bt_conn_prepare_events: 00> [00:00:34.765,563] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:34.773,803] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:34.780,517] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:34.789,154] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:34.797,210] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:34.805,847] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:34.814,483] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:34.822,631] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:34.829,467] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:34.836,853] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:34.848,632] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:34.855,773] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:34.863,037] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:34.871,429] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:34.878,326] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:34.886,657] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:34.894,042] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:34.903,533] bt_hci_core: process_events: count 2 00> [00:00:34.909,942] bt_hci_core: process_events: ev->state 4 00> [00:00:34.916,748] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:34.923,706] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:34.930,877] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:34.940,551] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:34.948,181] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:34.956,542] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:34.973,968] bt_hci_core: process_events: ev->state 0 00> [00:00:34.980,743] bt_conn: bt_conn_prepare_events: 00> [00:00:34.986,907] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:34.995,147] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:35.002,227] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:35.018,859] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:35.025,543] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:35.033,691] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:35.040,679] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:35.047,851] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:35.057,128] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:35.066,589] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:35.073,669] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:35.090,576] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:35.127,288] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:35.134,124] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:35.142,272] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 00> [00:00:35.149,383] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:35.157,592] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 00> [00:00:35.166,015] bt_hci_core: hci_event: event 0x3e 00> [00:00:35.172,271] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:35.179,718] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:35.194,580] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:35.203,979] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:35.210,876] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:35.217,956] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:35.224,853] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:35.232,055] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:35.240,234] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:35.247,863] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:35.256,500] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:35.264,312] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:35.271,820] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:35.280,456] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:35.288,024] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:35.296,936] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:35.305,541] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:35.313,446] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:35.320,556] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:35.332,336] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:35.340,667] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:35.348,022] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:35.357,482] bt_hci_core: process_events: count 2 00> [00:00:35.363,922] bt_hci_core: process_events: ev->state 4 00> [00:00:35.370,697] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:35.377,655] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:35.384,796] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:35.394,500] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:35.402,130] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:35.410,491] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:35.427,825] bt_hci_core: process_events: ev->state 1 00> [00:00:35.434,631] bt_conn: bt_conn_prepare_events: 00> [00:00:35.440,765] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:35.449,829] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:35.458,068] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:35.485,260] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:35.501,892] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:35.508,636] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:35.516,754] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:35.523,773] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:35.540,771] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:35.557,403] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:35.564,117] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:35.572,265] bt_hci_core: bt_recv: buf 0x20006b70 len 6 00> [00:00:35.579,284] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:35.589,050] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:35.596,008] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:35.604,583] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:35.611,755] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:35.628,723] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:35.645,355] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:35.652,069] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:35.660,186] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:35.667,205] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:35.674,194] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:35.683,471] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:35.690,368] bt_hci_core: Failed read remote features (-5) 00> [00:00:35.697,875] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:35.706,207] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:35.713,562] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:35.723,114] bt_hci_core: process_events: count 3 00> [00:00:35.729,553] bt_hci_core: process_events: ev->state 4 00> [00:00:35.736,328] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:35.743,286] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:35.750,457] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:35.760,131] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:35.767,883] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:35.776,306] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:35.793,731] bt_hci_core: process_events: ev->state 0 00> [00:00:35.800,506] bt_hci_core: process_events: ev->state 0 00> [00:00:35.807,312] bt_conn: bt_conn_prepare_events: 00> [00:00:35.813,446] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:35.821,716] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:35.828,765] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:35.845,397] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:35.852,111] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:35.860,260] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:35.867,248] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:35.874,237] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:35.883,514] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:35.890,411] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:35.897,033] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:35.904,205] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:35.912,445] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:35.920,776] bt_hci_core: hci_event: event 0x3e 00> [00:00:35.927,032] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:35.934,295] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:35.945,159] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:35.956,848] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:35.964,050] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:35.972,259] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 00> [00:00:35.980,621] bt_hci_core: hci_event: event 0x05 00> [00:00:35.986,877] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:35.996,185] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:36.003,173] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:36.012,023] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:36.018,402] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:36.025,604] bt_hci_core: process_events: count 2 00> [00:00:36.032,012] bt_hci_core: process_events: ev->state 0 00> [00:00:36.038,787] bt_hci_core: process_events: ev->state 1 00> [00:00:36.045,562] bt_conn: bt_conn_prepare_events: 00> [00:00:36.051,727] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:36.059,967] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:36.066,711] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:36.075,317] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:36.083,374] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:36.092,010] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:36.100,646] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:36.108,795] bt_att: att_chan_detach: chan 0x20003d88[0m 00> [00:00:36.115,600] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:36.122,985] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:36.134,765] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:36.141,937] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:36.149,200] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:36.157,592] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:36.164,489] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:36.172,821] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:36.180,175] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:36.189,666] bt_hci_core: process_events: count 2 00> [00:00:36.196,105] bt_hci_core: process_events: ev->state 4 00> [00:00:36.202,880] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:36.209,838] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:36.217,010] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:36.226,715] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:36.234,344] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:36.242,706] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:36.260,101] bt_hci_core: process_events: ev->state 0 00> [00:00:36.266,906] bt_conn: bt_conn_prepare_events: 00> [00:00:36.273,040] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:36.281,280] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:36.288,360] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:36.304,992] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:36.311,676] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:36.319,824] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:36.326,843] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:36.333,984] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:36.343,292] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:36.352,722] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:36.359,832] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:36.479,064] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:36.515,777] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:36.522,583] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:36.530,731] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:36.537,841] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:36.546,051] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:36.554,504] bt_hci_core: hci_event: event 0x3e 00> [00:00:36.560,729] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:36.568,176] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:36.583,038] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:36.592,437] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:36.599,334] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:36.606,414] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:36.613,311] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:36.620,544] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:36.628,692] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:36.636,322] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:36.644,958] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:36.652,770] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:36.660,308] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:36.668,914] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:36.676,483] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:36.685,394] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:36.694,030] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:36.701,934] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:36.709,045] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:36.720,794] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:36.729,125] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:36.736,480] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:36.745,941] bt_hci_core: process_events: count 2 00> [00:00:36.752,380] bt_hci_core: process_events: ev->state 4 00> [00:00:36.759,155] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:36.766,113] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:36.773,254] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:36.782,958] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:36.790,588] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:36.798,919] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:36.816,284] bt_hci_core: process_events: ev->state 1 00> [00:00:36.823,059] bt_conn: bt_conn_prepare_events: 00> [00:00:36.829,223] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:36.838,256] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:36.846,496] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:36.873,687] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:36.890,319] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:36.897,033] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:36.905,181] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:36.912,200] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:36.929,199] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:36.945,800] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:36.952,514] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:36.960,662] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:36.967,681] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:36.977,447] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:36.984,436] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:36.993,011] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:37.000,152] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:37.017,120] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:37.033,752] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:37.040,435] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:37.048,583] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:37.055,572] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:37.062,561] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:37.071,838] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:37.078,704] bt_hci_core: Failed read remote features (-5) 00> [00:00:37.086,212] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:37.094,543] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:37.101,898] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:37.111,480] bt_hci_core: process_events: count 3 00> [00:00:37.117,889] bt_hci_core: process_events: ev->state 4 00> [00:00:37.124,694] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:37.131,652] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:37.138,824] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:37.148,498] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:37.156,219] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:37.164,672] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:37.182,098] bt_hci_core: process_events: ev->state 0 00> [00:00:37.188,873] bt_hci_core: process_events: ev->state 0 00> [00:00:37.195,648] bt_conn: bt_conn_prepare_events: 00> [00:00:37.201,812] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:37.210,052] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:37.217,102] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:37.233,734] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:37.240,447] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:37.248,596] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:37.255,584] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:37.262,573] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:37.271,820] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:37.278,717] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:37.285,308] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:37.292,510] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:37.300,720] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:37.309,051] bt_hci_core: hci_event: event 0x3e 00> [00:00:37.315,307] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:37.322,570] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:37.333,404] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:37.345,092] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:37.352,294] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:37.360,504] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:37.368,835] bt_hci_core: hci_event: event 0x05 00> [00:00:37.375,122] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:37.384,460] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:37.391,448] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:37.400,268] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:37.406,677] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:37.413,879] bt_hci_core: process_events: count 2 00> [00:00:37.420,288] bt_hci_core: process_events: ev->state 0 00> [00:00:37.427,062] bt_hci_core: process_events: ev->state 1 00> [00:00:37.433,837] bt_conn: bt_conn_prepare_events: 00> [00:00:37.440,032] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:37.448,272] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:37.454,986] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:37.463,592] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:37.471,679] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:37.480,316] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:37.488,922] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:37.497,070] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:37.503,875] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:37.511,260] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:37.523,040] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:37.530,212] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:37.537,445] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:37.545,837] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:37.552,734] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:37.561,065] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:37.568,420] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:37.577,911] bt_hci_core: process_events: count 2 00> [00:00:37.584,350] bt_hci_core: process_events: ev->state 4 00> [00:00:37.591,125] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:37.598,083] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:37.605,255] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:37.614,929] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:37.622,589] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:37.630,920] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:37.648,345] bt_hci_core: process_events: ev->state 0 00> [00:00:37.655,120] bt_conn: bt_conn_prepare_events: 00> [00:00:37.661,254] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:37.669,525] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:37.676,574] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:37.693,176] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:37.699,890] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:37.708,038] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:37.715,026] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:37.722,198] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:37.731,475] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:37.740,905] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:37.748,016] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:38.277,557] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:38.314,239] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:38.321,044] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:38.329,193] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 00> [00:00:38.336,303] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:38.344,512] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 00> [00:00:38.352,966] bt_hci_core: hci_event: event 0x3e 00> [00:00:38.359,222] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:38.366,638] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:38.381,500] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:38.390,899] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:38.397,796] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:38.404,876] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:38.411,773] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:38.419,006] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:38.427,154] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:38.434,783] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:38.443,389] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:38.451,202] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:38.458,740] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:38.467,376] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:38.474,914] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:38.483,825] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:38.492,462] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:38.500,366] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:38.507,476] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:38.519,226] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:38.527,557] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:38.534,912] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:38.544,372] bt_hci_core: process_events: count 2 00> [00:00:38.550,811] bt_hci_core: process_events: ev->state 4 00> [00:00:38.557,586] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:38.564,544] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:38.571,685] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:38.581,359] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:38.588,989] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:38.597,351] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:38.614,685] bt_hci_core: process_events: ev->state 1 00> [00:00:38.621,490] bt_conn: bt_conn_prepare_events: 00> [00:00:38.627,624] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:38.636,688] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:38.644,897] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:38.672,088] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:38.688,720] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:38.695,434] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:38.703,582] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:38.710,601] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:38.727,600] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:38.744,232] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:38.750,946] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:38.759,094] bt_hci_core: bt_recv: buf 0x20006b70 len 6 00> [00:00:38.766,113] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:38.775,878] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:38.782,867] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:38.791,442] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:38.798,614] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:38.815,582] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:38.832,214] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:38.838,897] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:38.847,045] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:38.854,034] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:38.861,022] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:38.870,300] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:38.877,197] bt_hci_core: Failed read remote features (-5) 00> [00:00:38.884,704] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:38.893,035] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:38.900,360] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:38.909,942] bt_hci_core: process_events: count 3 00> [00:00:38.916,381] bt_hci_core: process_events: ev->state 4 00> [00:00:38.923,156] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:38.930,114] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:38.937,286] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:38.946,960] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:38.954,681] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:38.963,134] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:38.980,560] bt_hci_core: process_events: ev->state 0 00> [00:00:38.987,365] bt_hci_core: process_events: ev->state 0 00> [00:00:38.994,140] bt_conn: bt_conn_prepare_events: 00> [00:00:39.000,305] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:39.008,544] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:39.015,594] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:39.032,226] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:39.038,909] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:39.047,058] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:39.054,077] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:39.061,035] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:39.070,312] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:39.077,209] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:39.083,801] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:39.091,003] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:39.099,212] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:39.107,543] bt_hci_core: hci_event: event 0x3e 00> [00:00:39.113,800] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:39.121,063] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:39.131,896] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:39.143,585] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:39.150,787] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:39.158,996] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 00> [00:00:39.167,358] bt_hci_core: hci_event: event 0x05 00> [00:00:39.173,614] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:39.182,922] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:39.189,910] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:39.198,760] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:39.205,139] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:39.212,341] bt_hci_core: process_events: count 2 00> [00:00:39.218,750] bt_hci_core: process_events: ev->state 0 00> [00:00:39.225,555] bt_hci_core: process_events: ev->state 1 00> [00:00:39.232,330] bt_conn: bt_conn_prepare_events: 00> [00:00:39.238,494] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:39.246,734] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:39.253,479] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:39.262,084] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:39.270,172] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:39.278,808] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:39.287,445] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:39.295,593] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:39.302,398] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:39.309,783] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:39.321,563] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:39.328,735] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:39.335,968] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:39.344,360] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:39.351,287] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:39.359,619] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:39.366,973] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:39.376,464] bt_hci_core: process_events: count 2 00> [00:00:39.382,904] bt_hci_core: process_events: ev->state 4 00> [00:00:39.389,678] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:39.396,636] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:39.403,808] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:39.413,513] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:39.421,142] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:39.429,473] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:39.446,899] bt_hci_core: process_events: ev->state 0 00> [00:00:39.453,704] bt_conn: bt_conn_prepare_events: 00> [00:00:39.459,838] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:39.468,109] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:39.475,158] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:39.491,790] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:39.498,474] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:39.506,622] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:39.513,641] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:39.520,782] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:39.530,090] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:39.539,520] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:39.546,600] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:39.779,113] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:39.815,795] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:39.822,601] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:39.830,749] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:39.837,860] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:39.846,069] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:39.854,522] bt_hci_core: hci_event: event 0x3e 00> [00:00:39.860,778] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:39.868,225] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:39.883,087] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:39.892,456] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:39.899,353] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:39.906,463] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:39.913,360] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:39.920,562] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:39.928,741] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:39.936,370] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:39.944,976] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:39.952,789] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:39.960,327] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:39.968,963] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:39.976,531] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:39.985,443] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:39.994,079] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:40.001,953] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:40.009,063] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:40.020,843] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:40.029,174] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:40.036,529] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:40.045,989] bt_hci_core: process_events: count 2 00> [00:00:40.052,398] bt_hci_core: process_events: ev->state 4 00> [00:00:40.059,173] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:40.066,131] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:40.073,272] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:40.082,946] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:40.090,576] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:40.098,937] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:40.116,271] bt_hci_core: process_events: ev->state 1 00> [00:00:40.123,077] bt_conn: bt_conn_prepare_events: 00> [00:00:40.129,211] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:40.138,275] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:40.146,514] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:40.173,675] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:40.190,338] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:40.197,082] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:40.205,200] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:40.212,219] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:40.229,217] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:40.245,849] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:40.252,563] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:40.260,742] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:40.267,730] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:40.277,496] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:40.284,484] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:40.293,060] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:40.300,231] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:40.317,230] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:40.333,831] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:40.340,545] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:40.348,693] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:40.355,682] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:40.362,670] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:40.371,948] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:40.378,845] bt_hci_core: Failed read remote features (-5) 00> [00:00:40.386,352] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:40.394,683] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:40.402,038] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:40.411,590] bt_hci_core: process_events: count 3 00> [00:00:40.418,029] bt_hci_core: process_events: ev->state 4 00> [00:00:40.424,804] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:40.431,762] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:40.438,934] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:40.448,608] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:40.456,329] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:40.464,782] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:40.482,208] bt_hci_core: process_events: ev->state 0 00> [00:00:40.488,983] bt_hci_core: process_events: ev->state 0 00> [00:00:40.495,758] bt_conn: bt_conn_prepare_events: 00> [00:00:40.501,922] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:40.510,162] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:40.517,242] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:40.533,874] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:40.540,557] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:40.548,706] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:40.555,725] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:40.562,683] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:40.571,990] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:40.578,887] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:40.585,510] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:40.592,681] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:40.600,891] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:40.609,252] bt_hci_core: hci_event: event 0x3e 00> [00:00:40.615,509] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:40.622,772] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:40.633,605] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:40.645,294] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:40.652,496] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:40.660,705] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:40.669,067] bt_hci_core: hci_event: event 0x05 00> [00:00:40.675,323] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:40.684,631] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:40.691,619] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:40.700,469] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:40.706,878] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:40.714,050] bt_hci_core: process_events: count 2 00> [00:00:40.720,489] bt_hci_core: process_events: ev->state 0 00> [00:00:40.727,264] bt_hci_core: process_events: ev->state 1 00> [00:00:40.734,039] bt_conn: bt_conn_prepare_events: 00> [00:00:40.740,203] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:40.748,443] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:40.755,187] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:40.763,824] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:40.771,881] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:40.780,517] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:40.789,123] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:40.797,302] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:40.804,107] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:40.811,492] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:40.823,272] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:40.830,413] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:40.837,677] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:40.846,069] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:40.852,966] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:40.861,297] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:40.868,682] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:40.878,173] bt_hci_core: process_events: count 2 00> [00:00:40.884,582] bt_hci_core: process_events: ev->state 4 00> [00:00:40.891,357] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:40.898,315] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:40.905,487] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:40.915,191] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:40.922,821] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:40.931,152] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:40.948,577] bt_hci_core: process_events: ev->state 0 00> [00:00:40.955,352] bt_conn: bt_conn_prepare_events: 00> [00:00:40.961,517] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:40.969,757] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:40.976,837] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:40.993,469] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:41.000,152] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:41.008,300] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:41.015,289] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:41.022,460] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:41.031,738] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:41.041,168] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:41.048,248] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:41.582,672] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:41.619,354] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:41.626,159] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:41.634,307] bt_hci_core: bt_recv: buf 0x20006ba8 len 33 00> [00:00:41.641,418] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:41.649,627] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 33 00> [00:00:41.658,081] bt_hci_core: hci_event: event 0x3e 00> [00:00:41.664,337] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:41.671,783] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:41.686,614] [0m bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:41.696,014] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:41.702,911] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:41.709,991] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:41.716,888] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:41.724,121] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:41.732,269] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:41.739,898] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:41.748,535] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:41.756,317] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:41.763,854] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:41.772,491] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:41.780,029] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:41.788,940] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:41.797,576] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:41.805,450] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:41.812,591] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:41.824,340] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:41.832,672] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:41.840,026] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:41.849,487] bt_hci_core: process_events: count 2 00> [00:00:41.855,926] bt_hci_core: process_events: ev->state 4 00> [00:00:41.862,701] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:41.869,659] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:41.876,800] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:41.886,474] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:41.894,104] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:41.902,465] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:41.919,799] bt_hci_core: process_events: ev->state 1 00> [00:00:41.926,574] bt_conn: bt_conn_prepare_events: 00> [00:00:41.932,739] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:41.941,772] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:41.950,012] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:41.977,203] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:41.993,835] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:42.000,579] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:42.008,697] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:42.015,716] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:42.032,714] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:42.049,346] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:42.056,060] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:42.064,208] bt_hci_core: bt_recv: buf 0x20006b70 len 6 00> [00:00:42.071,197] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:42.080,963] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:42.087,951] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:42.096,527] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:42.103,698] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:42.120,697] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:42.137,298] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:42.144,012] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:42.152,160] [0m bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:42.159,149] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:42.166,137] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:42.175,415] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:42.182,312] bt_hci_core: Failed read remote features (-5) 00> [00:00:42.189,788] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:42.198,120] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:42.205,474] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:42.215,057] bt_hci_core: process_events: count 3 00> [00:00:42.221,466] bt_hci_core: process_events: ev->state 4 00> [00:00:42.228,240] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:42.235,198] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:42.242,370] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:42.252,075] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:42.259,796] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:42.268,218] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:42.285,644] bt_hci_core: process_events: ev->state 0 00> [00:00:42.292,419] bt_hci_core: process_events: ev->state 0 00> [00:00:42.299,194] bt_conn: bt_conn_prepare_events: 00> [00:00:42.305,358] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:42.313,598] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:42.320,648] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:42.337,280] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:42.343,963] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:42.352,111] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:42.359,100] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:42.366,088] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:42.375,366] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:42.382,232] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:42.388,854] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:42.396,026] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:42.404,235] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:42.412,597] bt_hci_core: hci_event: event 0x3e 00> [00:00:42.418,823] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:42.426,086] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:42.436,950] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:42.448,638] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:42.455,810] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:42.464,019] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 6 00> [00:00:42.472,351] bt_hci_core: hci_event: event 0x05 00> [00:00:42.478,637] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:42.487,945] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:42.494,934] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:42.503,784] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:42.510,162] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:42.517,333] bt_hci_core: process_events: count 2 00> [00:00:42.523,773] bt_hci_core: process_events: ev->state 0 00> [00:00:42.530,548] bt_hci_core: process_events: ev->state 1 00> [00:00:42.537,353] bt_conn: bt_conn_prepare_events: 00> [00:00:42.543,518] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:42.551,757] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:42.558,471] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:42.567,108] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:42.575,164] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:42.583,801] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:42.592,437] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:42.600,585] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:42.607,421] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:42.614,776] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:42.626,556] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:42.633,728] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:42.640,960] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:42.649,353] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:42.656,280] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:42.664,611] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:42.671,966] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:42.681,457] bt_hci_core: process_events: count 2 00> [00:00:42.687,896] bt_hci_core: process_events: ev->state 4 00> [00:00:42.694,671] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:42.701,629] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:42.708,801] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:42.718,505] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:42.726,135] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:42.734,466] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:42.751,892] bt_hci_core: process_events: ev->state 0 00> [00:00:42.758,666] bt_conn: bt_conn_prepare_events: 00> [00:00:42.764,831] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:42.773,071] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:42.780,120] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:42.796,722] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:42.803,436] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:42.811,584] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:42.818,572] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:42.825,744] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:42.835,021] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:42.844,451] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:42.851,531] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |. 00> [00:00:43.073,364] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 1f 0a 00 0f 00 01 01 87 d8 05 91 2d 6b 00 |.>...... .....-k. 00> 00 00 00 00 00 00 00 00 00 00 00 18 00 00 00 48 |........ .......H 00> 00 01 |.. 00> [00:00:43.110,076] bt_hci_driver: bt_rpmsg_evt_recv: len 31 00> [00:00:43.116,882] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b70) 00> [00:00:43.125,030] bt_hci_core: bt_recv: buf 0x20006b70 len 33 00> [00:00:43.132,141] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:43.140,350] bt_hci_core: rx_work_handler: buf 0x20006b70 type 1 len 33 00> [00:00:43.148,773] bt_hci_core: hci_event: event 0x3e 00> [00:00:43.155,029] bt_hci_core: hci_le_meta_event: subevent 0x0a 00> [00:00:43.162,475] bt_hci_core: bt_hci_le_enh_conn_complete: status 0x00 handle 15 role 1 peer 6B:2D:91:05:D8:87 (random) peer RPA 00:00:00:00:00:00 00> [00:00:43.177,337] bt_hci_core: bt_hci_le_enh_conn_complete: local RPA 00:00:00:00:00:00 00> [00:00:43.186,737] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:43.193,634] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:43.200,714] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:43.207,611] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:43.214,843] bt_conn: bt_conn_set_state: connecting-adv -> connected 00> [00:00:43.222,991] bt_l2cap: l2cap_accept: conn 0x20000f00 handle 15 00> [00:00:43.230,621] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001a70 00> [00:00:43.239,257] bt_l2cap: l2cap_connected: ch 0x20001a70 cid 0x0005 00> [00:00:43.247,070] bt_smp: bt_smp_accept: conn 0x20000f00 handle 15 00> [00:00:43.254,577] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20001b98 00> [00:00:43.263,214] bt_att: bt_att_accept: conn 0x20000f00 handle 15 00> [00:00:43.270,782] bt_att: att_chan_attach: att 0x20007ba8 chan 0x20003d88 flags 0 00> [00:00:43.279,693] bt_l2cap: bt_l2cap_chan_add: conn 0x20000f00 chan 0x20003d8c 00> [00:00:43.288,330] bt_att: bt_att_connected: chan 0x20003d8c cid 0x0004 00> [00:00:43.296,203] bt_gatt: bt_gatt_connected: conn 0x20000f00 00> [00:00:43.303,344] bt_att: bt_att_status: chan 0x20003d8c status 0x20003d9c 00> Connected 6B:2D:91:05:D8:87 (random) 00> 00> [00:00:43.315,093] bt_hci_core: bt_hci_cmd_create: opcode 0x2016 param_len 2 00> [00:00:43.323,425] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:43.330,810] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2016 len 5 00> [00:00:43.340,270] bt_hci_core: process_events: count 2 00> [00:00:43.346,710] bt_hci_core: process_events: ev->state 4 00> [00:00:43.353,485] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:43.360,443] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:43.367,584] bt_hci_core: send_cmd: Sending command 0x2016 (buf 0x20006f50) to driver 00> [00:00:43.377,288] bt_hci_core: bt_send: buf 0x20006f50 len 5 type 0 00> [00:00:43.384,918] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 5 00> [00:00:43.393,249] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 16 20 02 0f 00 |.. ... 00> [00:00:43.410,614] bt_hci_core: process_events: ev->state 1 00> [00:00:43.417,419] bt_conn: bt_conn_prepare_events: 00> [00:00:43.423,553] bt_conn: conn_prepare_events: Adding conn 0x20000f00 to poll list 00> [00:00:43.432,617] bt_hci_core: hci_tx_thread: Calling k_poll with 3 events 00> [00:00:43.440,856] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 00 0f 00 01 01 87 d8 05 91 2d 6b 00 00 00 00 00 |........ .-k..... 00> 00 00 00 00 00 00 00 18 00 00 00 48 00 01 |........ ...H.. 00> [00:00:43.468,017] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 3e 06 12 00 00 0f 00 00 |.>...... . 00> [00:00:43.484,680] bt_hci_driver: bt_rpmsg_evt_recv: len 6 00> [00:00:43.491,394] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006b8c) 00> [00:00:43.499,542] bt_hci_core: bt_recv: buf 0x20006b8c len 8 00> [00:00:43.506,530] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 3e 06 12 00 00 0f 00 00 |>....... 00> [00:00:43.523,559] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 05 04 00 0f 00 3e |......> 00> [00:00:43.540,191] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:43.546,905] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006ba8) 00> [00:00:43.555,053] bt_hci_core: bt_recv: buf 0x20006ba8 len 6 00> [00:00:43.562,072] bt_hci_core: hci_disconn_complete_prio: status 0x00 handle 15 reason 0x3e 00> [00:00:43.571,807] bt_conn: bt_conn_ref: handle 15 ref 2 -> 3 00> [00:00:43.578,796] bt_conn: bt_conn_set_state: connected -> disconnect-complete 00> [00:00:43.587,402] bt_conn: bt_conn_unref: handle 15 ref 3 -> 2 00> [00:00:43.594,543] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 05 04 00 0f 00 3e |.....> 00> [00:00:43.611,541] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 16 20 |...... 00> [00:00:43.628,143] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:43.634,857] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:43.643,005] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:43.649,993] bt_hci_core: hci_cmd_status: opcode 0x2016 00> [00:00:43.656,982] bt_hci_core: hci_cmd_done: opcode 0x2016 status 0x02 buf 0x20006f50 00> [00:00:43.666,259] bt_hci_core: opcode 0x2016 status 0x02 00> [00:00:43.673,126] bt_hci_core: Failed read remote features (-5) 00> [00:00:43.680,633] bt_hci_core: bt_hci_cmd_create: opcode 0x2032 param_len 7 00> [00:00:43.688,964] bt_hci_core: bt_hci_cmd_create: buf 0x20006f6c 00> [00:00:43.696,319] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f6c opcode 0x2032 len 10 00> [00:00:43.705,871] bt_hci_core: process_events: count 3 00> [00:00:43.712,310] bt_hci_core: process_events: ev->state 4 00> [00:00:43.719,085] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:43.726,043] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:43.733,215] bt_hci_core: send_cmd: Sending command 0x2032 (buf 0x20006f6c) to driver 00> [00:00:43.742,889] bt_hci_core: bt_send: buf 0x20006f6c len 10 type 0 00> [00:00:43.750,610] bt_hci_driver: bt_rpmsg_send: buf 0x20006f6c type 0 len 10 00> [00:00:43.759,063] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 32 20 07 0f 00 00 02 02 00 00 |.2 ..... ... 00> [00:00:43.776,489] bt_hci_core: process_events: ev->state 0 00> [00:00:43.783,264] bt_hci_core: process_events: ev->state 0 00> [00:00:43.790,069] bt_conn: bt_conn_prepare_events: 00> [00:00:43.796,203] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:43.804,443] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:43.811,523] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0f 04 02 01 32 20 |.....2 00> [00:00:43.828,155] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:43.834,838] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f6c) 00> [00:00:43.842,987] bt_hci_core: bt_recv: buf 0x20006f6c len 6 00> [00:00:43.850,006] bt_hci_core: hci_cmd_status: opcode 0x2032 00> [00:00:43.856,964] bt_hci_core: hci_cmd_done: opcode 0x2032 status 0x02 buf 0x20006f6c 00> [00:00:43.866,241] bt_hci_core: opcode 0x2032 status 0x02 00> [00:00:43.873,138] bt_hci_core: Failed LE Set PHY (-5) 00> [00:00:43.879,730] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:43.886,932] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:43.895,141] bt_hci_core: rx_work_handler: buf 0x20006b8c type 1 len 8 00> [00:00:43.903,503] bt_hci_core: hci_event: event 0x3e 00> [00:00:43.909,759] bt_hci_core: hci_le_meta_event: subevent 0x12 00> [00:00:43.916,992] bt_adv: bt_hci_le_adv_set_terminated: status 0x00 adv_handle 0 conn_handle 0x0f num 0 00> [00:00:43.927,856] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> Advertiser[0] 0x20000d28 connected conn 0x20000f00 00> 00> [00:00:43.939,544] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:43.946,746] bt_hci_core: rx_work_handler: Getting net_buf from queue 00> [00:00:43.954,956] bt_hci_core: rx_work_handler: buf 0x20006ba8 type 1 len 6 00> [00:00:43.963,287] bt_hci_core: hci_event: event 0x05 00> [00:00:43.969,543] bt_hci_core: hci_disconn_complete: status 0x00 handle 15 reason 0x3e 00> [00:00:43.978,881] bt_conn: bt_conn_ref: handle 15 ref 1 -> 2 00> [00:00:43.985,870] bt_conn: bt_conn_set_state: disconnect-complete -> disconnected 00> [00:00:43.994,689] bt_conn: tx_notify: conn 0x20000f00 00> [00:00:44.001,098] bt_conn: bt_conn_unref: handle 15 ref 2 -> 1 00> [00:00:44.008,270] bt_hci_core: process_events: count 2 00> [00:00:44.014,709] bt_hci_core: process_events: ev->state 0 00> [00:00:44.021,484] bt_hci_core: process_events: ev->state 1 00> [00:00:44.028,259] bt_conn: bt_conn_prepare_events: 00> [00:00:44.034,423] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:44.042,663] bt_conn: deferred_work: conn 0x20000f00 00> [00:00:44.049,377] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001a70 00> [00:00:44.058,013] bt_l2cap: l2cap_disconnected: ch 0x20001a70 cid 0x0005 00> [00:00:44.066,070] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20001b98 00> [00:00:44.074,676] bt_l2cap: bt_l2cap_chan_del: conn 0x20000f00 chan 0x20003d8c 00> [00:00:44.083,312] bt_att: bt_att_disconnected: chan 0x20003d8c cid 0x0004 00> [00:00:44.091,461] bt_att: att_chan_detach: chan 0x20003d88 00> [00:00:44.098,266] bt_gatt: bt_gatt_disconnected: conn 0x20000f00 00> [00:00:44.105,651] bt_att: bt_att_released: chan 0x20003d88 00> Disconnected: 6B:2D:91:05:D8:87 (random) (reason 62) 00> 00> [00:00:44.117,431] bt_conn: bt_conn_unref: handle 15 ref 1 -> 0 00> [00:00:44.124,603] bt_adv: bt_le_adv_resume: No valid legacy adv 00> [00:00:44.131,835] bt_conn: bt_conn_set_state: disconnected -> connecting-adv 00> [00:00:44.140,228] bt_conn: bt_conn_ref: handle 0 ref 1 -> 2 00> [00:00:44.147,155] bt_hci_core: bt_hci_cmd_create: opcode 0x2039 param_len 6 00> [00:00:44.155,456] bt_hci_core: bt_hci_cmd_create: buf 0x20006f50 00> [00:00:44.162,841] bt_hci_core: bt_hci_cmd_send_sync: buf 0x20006f50 opcode 0x2039 len 9 00> [00:00:44.172,332] bt_hci_core: process_events: count 2 00> [00:00:44.178,741] bt_hci_core: process_events: ev->state 4 00> [00:00:44.185,546] bt_hci_core: send_cmd: calling net_buf_get 00> [00:00:44.192,504] bt_hci_core: send_cmd: calling sem_take_wait 00> [00:00:44.199,676] bt_hci_core: send_cmd: Sending command 0x2039 (buf 0x20006f50) to driver 00> [00:00:44.209,350] bt_hci_core: bt_send: buf 0x20006f50 len 9 type 0 00> [00:00:44.216,979] bt_hci_driver: bt_rpmsg_send: buf 0x20006f50 type 0 len 9 00> [00:00:44.225,341] bt_hci_driver: bt_rpmsg_send: Final HCI buffer: 00> 01 39 20 06 01 01 00 00 00 00 |.9 ..... .. 00> [00:00:44.242,736] bt_hci_core: process_events: ev->state 0 00> [00:00:44.249,542] bt_conn: bt_conn_prepare_events: 00> [00:00:44.255,676] bt_hci_core: hci_tx_thread: Calling k_poll with 2 events 00> [00:00:44.263,916] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> [00:00:44.270,996] bt_hci_driver: bt_rpmsg_rx: RPMsg data: 00> 04 0e 04 01 39 20 00 |....9 . 00> [00:00:44.287,597] bt_hci_driver: bt_rpmsg_evt_recv: len 4 00> [00:00:44.294,311] bt_hci_driver: bt_rpmsg_rx: Calling bt_recv(0x20006f50) 00> [00:00:44.302,459] bt_hci_core: bt_recv: buf 0x20006f50 len 6 00> [00:00:44.309,448] bt_hci_core: hci_cmd_complete: opcode 0x2039 00> [00:00:44.316,619] bt_hci_core: hci_cmd_done: opcode 0x2039 status 0x00 buf 0x20006f50 00> [00:00:44.325,897] bt_hci_core: bt_hci_cmd_send_sync: rsp 0x20006f50 opcode 0x2039 len 1 00> [00:00:44.335,327] bt_conn: bt_conn_unref: handle 0 ref 2 -> 1 00> [00:00:44.342,437] bt_hci_driver: bt_rpmsg_rx: RX buf payload: 00> 94 |.