ESP32 + BTStack + GEJIN BT headphones

rst:0x10 (RTCWDT_RTC_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0018,len:4
load:0x3fff001c,len:4468
load:0x40078000,len:0
load:0x40078000,len:12988
entry 0x40078d8c
I (102) cpu_start: Pro cpu up.
I (102) cpu_start: Single core mode
I (102) heap_init: Initializing. RAM available for dynamic allocation:
I (106) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
I (112) heap_init: At 3FFCDA10 len 000125F0 (73 KiB): DRAM
I (118) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
I (124) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (131) heap_init: At 40093170 len 0000CE90 (51 KiB): IRAM
I (137) cpu_start: Pro cpu start user code
I (5) cpu_start: Starting scheduler on PRO CPU.
BTstack: setup
[00:00:00.006] LOG -- btstack_run_loop_freertos.c.260: run loop task 0x3ffd0974
[00:00:00.008] LOG -- btstack_run_loop_freertos.c.263: run loop init, queue item size 8
[00:00:00.016] LOG -- main.c.266: transport_register_packet_handler
[00:00:00.027] LOG -- btstack_tlv_esp32.c.54: tag 42544400 -> 42544400
[00:00:00.028] LOG -- btstack_tlv_esp32.c.68: read tag 42544400
[00:00:00.034] LOG -- btstack_tlv_esp32.c.54: tag 42544401 -> 42544401
[00:00:00.040] LOG -- btstack_tlv_esp32.c.68: read tag 42544401
[00:00:00.046] LOG -- btstack_tlv_esp32.c.54: tag 42544402 -> 42544402
[00:00:00.052] LOG -- btstack_tlv_esp32.c.68: read tag 42544402
[00:00:00.058] LOG -- btstack_tlv_esp32.c.54: tag 42544403 -> 42544403
[00:00:00.064] LOG -- btstack_tlv_esp32.c.68: read tag 42544403
[00:00:00.070] LOG -- btstack_tlv_esp32.c.54: tag 42544404 -> 42544404
[00:00:00.076] LOG -- btstack_tlv_esp32.c.68: read tag 42544404
[00:00:00.082] LOG -- btstack_tlv_esp32.c.54: tag 42544405 -> 42544405
[00:00:00.088] LOG -- btstack_tlv_esp32.c.68: read tag 42544405
[00:00:00.093] LOG -- btstack_tlv_esp32.c.54: tag 42544406 -> 42544406
[00:00:00.100] LOG -- btstack_tlv_esp32.c.68: read tag 42544406
[00:00:00.105] LOG -- btstack_tlv_esp32.c.54: tag 42544407 -> 42544407
[00:00:00.112] LOG -- btstack_tlv_esp32.c.68: read tag 42544407
[00:00:00.117] LOG -- btstack_tlv_esp32.c.54: tag 42544408 -> 42544408
[00:00:00.123] LOG -- btstack_tlv_esp32.c.68: read tag 42544408
[00:00:00.129] LOG -- btstack_tlv_esp32.c.54: tag 42544409 -> 42544409
[00:00:00.135] LOG -- btstack_tlv_esp32.c.68: read tag 42544409
[00:00:00.141] LOG -- btstack_tlv_esp32.c.54: tag 4254440a -> 4254440A
[00:00:00.147] LOG -- btstack_tlv_esp32.c.68: read tag 4254440A
[00:00:00.153] LOG -- btstack_tlv_esp32.c.54: tag 4254440b -> 4254440B
[00:00:00.159] LOG -- btstack_tlv_esp32.c.68: read tag 4254440B
[00:00:00.165] LOG -- btstack_tlv_esp32.c.54: tag 4254440c -> 4254440C
[00:00:00.171] LOG -- btstack_tlv_esp32.c.68: read tag 4254440C
[00:00:00.177] LOG -- btstack_tlv_esp32.c.54: tag 4254440d -> 4254440D
[00:00:00.183] LOG -- btstack_tlv_esp32.c.68: read tag 4254440D
[00:00:00.189] LOG -- btstack_tlv_esp32.c.54: tag 4254440e -> 4254440E
[00:00:00.195] LOG -- btstack_tlv_esp32.c.68: read tag 4254440E
[00:00:00.200] LOG -- btstack_tlv_esp32.c.54: tag 4254440f -> 4254440F
[00:00:00.207] LOG -- btstack_tlv_esp32.c.68: read tag 4254440F
[00:00:00.212] LOG -- le_device_db_tlv.c.157: num valid le device entries 0
[00:00:00.219] LOG -- l2cap.c.3350: L2CAP_REGISTER_SERVICE psm 0x19 mtu 65535
[00:00:00.226] LOG -- l2cap.c.3350: L2CAP_REGISTER_SERVICE psm 0x19 mtu 65535
[00:00:00.233] LOG -- l2cap.c.3355: l2cap_register_service: PSM 25 already registered
[00:00:00.240] LOG -- l2cap.c.3350: L2CAP_REGISTER_SERVICE psm 0x17 mtu 65535
[00:00:00.247] LOG -- l2cap.c.3350: L2CAP_REGISTER_SERVICE psm 0x1 mtu 65535
loaded mod 'nao-deceased by disease', size 7600
[00:00:00.259] LOG -- hci.c.2638: hci_power_control: 1, current mode 0
[00:00:00.264] LOG -- main.c.203: transport_init
[00:00:00.269] LOG -- main.c.219: transport_open
I (273) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (465) phy: phy_version: 366.0, ba9923d, Oct 31 2017, 18:06:17, 0, 0
[00:00:00.465] LOG -- hci.c.3693: BTSTACK_EVENT_STATE 1
[00:00:00.466] EVT <= 60 01 01 
[00:00:00.469] CMD => 03 0C 00 
BTstack: execute run loop
[00:00:00.474] EVT <= 6E 00 
[00:00:00.477] EVT <= 0E 04 05 03 0C 00 
[00:00:00.480] CMD => 01 10 00 
[00:00:00.483] EVT <= 0E 0C 05 01 10 00 08 0E 03 08 60 00 0E 03 
[00:00:00.489] LOG -- hci.c.1801: Manufacturer: 0x0060
[00:00:00.494] EVT <= 6E 00 
[00:00:00.496] CMD => 14 0C 00 
[00:00:00.499] EVT <= 0E FC 05 14 0C 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
[00:00:00.567] LOG -- hci.c.1715: local name: 
[00:00:00.571] LOG -- hci.c.1510: Received local name, need baud change 0
[00:00:00.578] EVT <= 6E 00 
[00:00:00.581] CMD => 02 10 00 
[00:00:00.584] EVT <= 0E 44 05 02 10 00 BF FF FF 03 CC FF EF FF 3F FF FC 1F F2 0F E8 FE 3F F7 8F FF 1C 00 04 00 61 F7 FF FF 7F 38 C0 FF 7F FF FF 0F 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
[00:00:00.604] LOG -- hci.c.1811: Local supported commands summary 0x3f
[00:00:00.610] EVT <= 6E 00 
[00:00:00.613] CMD => 09 10 00 
[00:00:00.616] EVT <= 0E 0A 05 09 10 00 D2 8D 06 A4 AE 30 
[00:00:00.621] LOG -- hci.c.1763: Local Address, Status: 0x00: Addr: 30:AE:A4:06:8D:D2
[00:00:00.628] EVT <= 6E 00 
[00:00:00.631] CMD => 05 10 00 
[00:00:00.634] EVT <= 0E 0B 05 05 10 00 FD 03 FF 09 00 04 00 
[00:00:00.639] LOG -- hci.c.1732: hci_read_buffer_size: ACL size module 1021 -> used 1021, count 9 / SCO size 255, count 4
[00:00:00.650] EVT <= 6E 00 
[00:00:00.653] CMD => 03 10 00 
[00:00:00.656] EVT <= 0E 0C 05 03 10 00 BF EE CD FE DB FF 7B 87 
[00:00:00.661] LOG -- hci.c.1790: Packet types cc18, eSCO 1
[00:00:00.667] LOG -- hci.c.1793: BR/EDR support 1, LE support 1
[00:00:00.672] EVT <= 6E 00 
[00:00:00.675] CMD => 33 0C 07 00 04 3C 14 00 0A 00 
[00:00:00.680] EVT <= 0E 04 05 33 0C 00 
[00:00:00.683] EVT <= 6E 00 
[00:00:00.686] CMD => 31 0C 01 03 
[00:00:00.689] EVT <= 0E 04 05 31 0C 00 
[00:00:00.693] EVT <= 6E 00 
[00:00:00.695] CMD => 01 0C 08 FF FF FF FF FF FF FF 3F 
[00:00:00.700] EVT <= 0E 04 05 01 0C 00 
[00:00:00.704] EVT <= 6E 00 
[00:00:00.706] CMD => 56 0C 01 01 
[00:00:00.710] EVT <= 0E 04 05 56 0C 00 
[00:00:00.713] EVT <= 6E 00 
[00:00:00.716] CMD => 18 0C 02 00 60 
[00:00:00.719] EVT <= 0E 04 05 18 0C 00 
[00:00:00.723] EVT <= 6E 00 
[00:00:00.725] CMD => 24 0C 03 08 04 20 
[00:00:00.729] EVT <= 0E 04 05 24 0C 00 
[00:00:00.733] EVT <= 6E 00 
[00:00:00.735] CMD => 13 0C F8 41 32 44 50 20 53 6F 75 72 63 65 20 44 65 6D 6F 20 33 30 3A 41 45 3A 41 34 3A 30 36 3A 38 44 3A 44 32 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
[00:00:00.803] EVT <= 0E 04 05 13 0C 00 
[00:00:00.806] EVT <= 6E 00 
[00:00:00.809] CMD => 52 0C F1 00 23 09 41 32 44 50 20 53 6F 75 72 63 65 20 44 65 6D 6F 20 33 30 3A 41 45 3A 41 34 3A 30 36 3A 38 44 3A 44 32 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
[00:00:00.875] EVT <= 0E 04 05 52 0C 00 
[00:00:00.878] EVT <= 6E 00 
[00:00:00.881] CMD => 45 0C 01 00 
[00:00:00.884] EVT <= 0E 04 05 45 0C 00 
[00:00:00.888] EVT <= 6E 00 
[00:00:00.890] CMD => 1A 0C 01 03 
[00:00:00.894] EVT <= 0E 04 05 1A 0C 00 
[00:00:00.897] LOG -- hci.c.3805: BTSTACK_EVENT_DISCOVERABLE_ENABLED 1
[00:00:00.903] EVT <= 66 01 01 
[00:00:00.906] EVT <= 6E 00 
[00:00:00.909] CMD => 02 20 00 
[00:00:00.912] EVT <= 0E 07 05 02 20 00 FB 00 0A 
[00:00:00.916] LOG -- hci.c.1743: hci_le_read_buffer_size: size 251, count 10
[00:00:00.923] EVT <= 6E 00 
[00:00:00.926] CMD => 6D 0C 02 01 00 
[00:00:00.929] EVT <= 0E 04 05 6D 0C 00 
[00:00:00.933] EVT <= 6E 00 
[00:00:00.935] CMD => 01 20 08 FF 01 00 00 00 00 00 00 
[00:00:00.940] EVT <= 0E 04 05 01 20 00 
[00:00:00.944] EVT <= 6E 00 
[00:00:00.946] CMD => 2F 20 00 
[00:00:00.949] EVT <= 0E 0C 05 2F 20 00 FB 00 48 08 FB 00 48 08 
[00:00:00.955] LOG -- hci.c.1750: hci_le_read_maximum_data_length: tx octets 251, tx time 2120 us
[00:00:00.964] EVT <= 6E 00 
[00:00:00.966] CMD => 24 20 04 FB 00 48 08 
[00:00:00.970] EVT <= 0E 04 05 24 20 00 
[00:00:00.974] EVT <= 6E 00 
[00:00:00.976] CMD => 0F 20 00 
[00:00:00.979] EVT <= 0E 05 05 0F 20 00 0C 
[00:00:00.983] LOG -- hci.c.1756: hci_le_read_white_list_size: size 12
[00:00:00.989] EVT <= 6E 00 
[00:00:00.992] CMD => 0B 20 07 01 E0 01 30 00 00 00 
[00:00:00.997] EVT <= 0E 04 05 0B 20 00 
[00:00:01.000] LOG -- hci.c.1376: hci_init_done -> HCI_STATE_WORKING
[00:00:01.006] LOG -- hci.c.3693: BTSTACK_EVENT_STATE 2
[00:00:01.011] EVT <= 60 01 02 
BTstack: up and running.
[00:00:01.016] EVT <= 6E 00 
[00:00:04.522] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x1 mtu 1691 -> local mtu 1691
[00:00:04.522] LOG -- hci.c.3397: Create_connection to 22:22:22:6A:A7:7B
[00:00:04.527] LOG -- hci.c.184: create_connection_for_addr 22:22:22:6A:A7:7B, type ff
[00:00:04.535] LOG -- hci.c.3409: conn state 0
[00:00:04.539] CMD => 05 04 0D 7B A7 6A 22 22 22 18 CC 00 00 00 00 01 
 - Create AVDTP Source connection to addr 22:22:22:6A:A7:7B, cid 0x01.
[00:00:04.551] EVT <= 6E 00 
[00:00:04.554] EVT <= 0F 04 00 05 05 04 
[00:00:19.513] EVT <= 03 0B 00 81 00 7B A7 6A 22 22 22 01 00 
[00:00:19.513] LOG -- hci.c.1905: Connection_complete (status=0) 22:22:22:6A:A7:7B
[00:00:19.515] LOG -- hci.c.1918: New connection: handle 129, 22:22:22:6A:A7:7B
[00:00:19.522] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 1
[00:00:19.528] EVT <= 61 01 01 
[00:00:19.531] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:19.538] CMD => 1B 04 02 81 00 
[00:00:19.542] EVT <= 0F 04 00 05 1B 04 
[00:00:19.545] EVT <= 0B 0B 00 81 00 BF 3A 85 FA 88 BD 59 87 
[00:00:19.551] LOG -- hci.c.1984: HCI_EVENT_READ_REMOTE_SUPPORTED_FEATURES_COMPLETE, bonding flags 6, eSCO 1
[00:00:19.560] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 1 = 1
[00:00:19.570] EVT <= 6E 00 
[00:00:19.573] ACL => 81 00 0C 00 08 00 01 00 02 01 04 00 01 00 40 00 
[00:00:19.579] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x40
[00:00:19.585] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x40
[00:00:19.591] EVT <= 13 05 01 81 00 01 00 
[00:00:19.595] EVT <= 6E 00 
[00:00:19.661] EVT <= 1B 03 81 00 05 
[00:00:19.665] ACL <= 81 20 10 00 0C 00 01 00 03 01 08 00 4B 00 40 00 00 00 00 00 
[00:00:19.666] LOG -- l2cap.c.2421: L2CAP signaling handler code 3, state 10
[00:00:19.670] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x40
[00:00:19.676] ACL => 81 00 10 00 0C 00 01 00 04 02 08 00 4B 00 00 00 01 02 9B 06 
[00:00:19.684] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x40
[00:00:19.690] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x40
[00:00:19.696] EVT <= 13 05 01 81 00 01 00 
[00:00:19.700] EVT <= 6E 00 
[00:00:19.702] CMD => 35 0C 05 01 81 00 01 00 
[00:00:19.707] EVT <= 6E 00 
[00:00:19.746] ACL <= 81 20 12 00 0E 00 01 00 05 02 0A 00 40 00 00 00 00 00 01 02 A0 02 
[00:00:19.747] LOG -- l2cap.c.2421: L2CAP signaling handler code 5, state 11
[00:00:19.750] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x40
[00:00:19.756] LOG -- l2cap.c.2353: l2cap_signaling_handle_configure_response
[00:00:19.763] CMD => 35 0C 05 01 81 00 01 00 
[00:00:19.768] EVT <= 6E 00 
[00:00:19.774] ACL <= 81 20 10 00 0C 00 01 00 04 12 08 00 40 00 00 00 01 02 A0 02 
[00:00:19.777] LOG -- l2cap.c.2421: L2CAP signaling handler code 4, state 11
[00:00:19.784] LOG -- l2cap.c.2276: Remote MTU 672
[00:00:19.789] ACL => 81 00 12 00 0E 00 01 00 05 12 0A 00 4B 00 00 00 00 00 01 02 A0 02 
[00:00:19.796] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr 22:22:22:6A:A7:7B handle 0x81 psm 0x1 local_cid 0x40 remote_cid 0x4b local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:19.813] EVT <= 70 16 00 7B A7 6A 22 22 22 81 00 01 00 40 00 4B 00 9B 06 A0 02 00 00 00 
[00:00:19.822] EVT <= 13 05 01 81 00 01 00 
[00:00:19.826] EVT <= 6E 00 
[00:00:19.828] EVT <= 78 02 40 00 
[00:00:19.831] ACL => 81 00 18 00 14 00 4B 00 06 00 01 00 0F 35 03 19 00 19 06 9B 35 05 0A 00 01 FF FF 00 
[00:00:19.841] EVT <= 6E 00 
[00:00:19.843] CMD => 35 0C 05 01 81 00 01 00 
[00:00:19.848] EVT <= 6E 00 
[00:00:19.866] EVT <= 13 05 01 81 00 01 00 
[00:00:19.919] ACL <= 81 20 36 00 3E 00 40 00 07 00 01 00 39 00 36 36 00 33 36 00 30 09 00 01 35 03 19 11 0B 09 00 04 35 10 35 06 19 01 00 09 00 19 35 06 19 00 19 09 01 03 09 00 09 35 08 35 06 19 
[00:00:19.926] CMD => 35 0C 05 01 81 00 01 00 
[00:00:19.930] ACL <= 81 10 0C 00 11 0D 09 01 03 09 03 11 09 00 01 00 
[00:00:19.936] LOG -- l2cap.c.1805: L2CAP_DISCONNECT local_cid 0x40 reason 0x0
[00:00:19.943] EVT <= 6E 00 
[00:00:19.946] ACL => 81 00 0C 00 08 00 01 00 06 03 04 00 4B 00 40 00 
[00:00:19.952] EVT <= 6E 00 
[00:00:19.954] CMD => 35 0C 05 01 81 00 01 00 
[00:00:19.959] EVT <= 13 05 01 81 00 01 00 
[00:00:19.962] EVT <= 6E 00 
[00:00:20.039] ACL <= 81 20 0C 00 08 00 01 00 07 03 04 00 4B 00 40 00 
[00:00:20.039] LOG -- l2cap.c.2421: L2CAP signaling handler code 7, state 13
[00:00:20.041] LOG -- l2cap.c.922: L2CAP_EVENT_CHANNEL_CLOSED local_cid 0x40
[00:00:20.048] EVT <= 71 02 40 00 
[00:00:20.051] LOG -- sdp_client.c.472: SDP Client disconnected.
[00:00:20.057] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x19 mtu 1691 -> local mtu 1691
[00:00:20.067] LOG -- l2cap.c.1790: l2cap_create_channel, hci connection already exists
[00:00:20.075] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:20.082] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 25 = 0
[00:00:20.092] LOG -- hci.c.3889: gap_request_security_level 2, current level 0
[00:00:20.099] CMD => 35 0C 05 01 81 00 01 00 
[00:00:20.103] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x40
[00:00:20.109] EVT <= 6E 00 
[00:00:20.112] CMD => 11 04 02 81 00 
[00:00:20.116] EVT <= 0F 04 00 05 11 04 
[00:00:20.119] EVT <= 17 06 7B A7 6A 22 22 22 
[00:00:20.123] LOG -- hci.c.1991: HCI_EVENT_LINK_KEY_REQUEST
[00:00:20.129] EVT <= 6E 00 
[00:00:20.131] LOG -- hci.c.3164: responding to link key request
[00:00:20.137] LOG -- btstack_tlv_esp32.c.54: tag 42544c00 -> 42544C00
[00:00:20.143] LOG -- btstack_tlv_esp32.c.68: read tag 42544C00
[00:00:20.149] LOG -- btstack_tlv_esp32.c.54: tag 42544c01 -> 42544C01
[00:00:20.155] LOG -- btstack_tlv_esp32.c.68: read tag 42544C01
[00:00:20.161] LOG -- btstack_tlv_esp32.c.54: tag 42544c02 -> 42544C02
[00:00:20.167] LOG -- btstack_tlv_esp32.c.68: read tag 42544C02
[00:00:20.173] LOG -- btstack_tlv_esp32.c.54: tag 42544c03 -> 42544C03
[00:00:20.179] LOG -- btstack_tlv_esp32.c.68: read tag 42544C03
[00:00:20.184] LOG -- btstack_tlv_esp32.c.54: tag 42544c04 -> 42544C04
[00:00:20.191] LOG -- btstack_tlv_esp32.c.68: read tag 42544C04
[00:00:20.196] LOG -- btstack_tlv_esp32.c.54: tag 42544c05 -> 42544C05
[00:00:20.202] LOG -- btstack_tlv_esp32.c.68: read tag 42544C05
[00:00:20.208] LOG -- btstack_tlv_esp32.c.54: tag 42544c06 -> 42544C06
[00:00:20.214] LOG -- btstack_tlv_esp32.c.68: read tag 42544C06
[00:00:20.220] LOG -- btstack_tlv_esp32.c.54: tag 42544c07 -> 42544C07
[00:00:20.226] LOG -- btstack_tlv_esp32.c.68: read tag 42544C07
[00:00:20.232] LOG -- btstack_tlv_esp32.c.54: tag 42544c08 -> 42544C08
[00:00:20.238] LOG -- btstack_tlv_esp32.c.68: read tag 42544C08
[00:00:20.244] LOG -- btstack_tlv_esp32.c.54: tag 42544c09 -> 42544C09
[00:00:20.250] LOG -- btstack_tlv_esp32.c.68: read tag 42544C09
[00:00:20.256] LOG -- btstack_tlv_esp32.c.54: tag 42544c0a -> 42544C0A
[00:00:20.262] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0A
[00:00:20.268] LOG -- btstack_tlv_esp32.c.54: tag 42544c0b -> 42544C0B
[00:00:20.274] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0B
[00:00:20.280] LOG -- btstack_tlv_esp32.c.54: tag 42544c0c -> 42544C0C
[00:00:20.286] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0C
[00:00:20.291] LOG -- btstack_tlv_esp32.c.54: tag 42544c0d -> 42544C0D
[00:00:20.298] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0D
[00:00:20.304] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0d, addr 30:21:8A:0B:48:C1
[00:00:20.311] LOG -- btstack_tlv_esp32.c.54: tag 42544c0e -> 42544C0E
[00:00:20.317] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0E
[00:00:20.323] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0e, addr 22:22:22:6A:A7:7B
[00:00:20.331] CMD => 0B 04 16 7B A7 6A 22 22 22 1D 66 C0 C7 86 1D EB 9F 11 5B E4 55 08 43 DE 4E 
[00:00:20.340] EVT <= 0E 0A 05 0B 04 00 7B A7 6A 22 22 22 
[00:00:20.345] EVT <= 6E 00 
[00:00:20.449] EVT <= 06 03 05 81 00 
[00:00:20.449] LOG -- hci.c.3787: hci_emit_security_level 0 for handle 81
[00:00:20.450] EVT <= E0 03 81 00 00 
[00:00:20.451] LOG -- l2cap.c.2104: l2cap - security level update
[00:00:20.457] LOG -- l2cap.c.2113: channel state 6: actual 0 >= required 2?
[00:00:20.464] CMD => 06 04 03 81 00 05 
[00:00:20.468] EVT <= 0F 04 00 05 06 04 
[00:00:20.471] EVT <= 6E 00 
[00:00:20.542] EVT <= 05 04 00 81 00 16 
[00:00:20.542] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x41
[00:00:20.542] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x6a addr 22:22:22:6A:A7:7B handle 0x81 psm 0x19 local_cid 0x41 remote_cid 0x0 local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:20.558] EVT <= 70 16 6A 7B A7 6A 22 22 22 81 00 19 00 41 00 00 00 9B 06 A0 02 00 00 00 
[00:00:20.566] LOG -- avdtp.c.542: L2CAP_EVENT_CHANNEL_OPENED: status 106, cid 0x41 , signaling connection 0x3ffd5000 

[00:00:20.577] LOG -- avdtp.c.551: L2CAP connection to 22:22:22:6A:A7:7B failed. status code 0x6a
[00:00:20.585] LOG -- a2dp_source.c.185: AVDTP_SUBEVENT_SIGNALING_CONNECTION failed status 106 ---
A2DP: Stream establishment failed: status 0x6a.
[00:00:20.598] LOG -- hci.c.826: Connection closed: handle 0x81, 22:22:22:6A:A7:7B
[00:00:20.605] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 0
[00:00:20.612] EVT <= 61 01 00 
[00:00:34.199] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x1 mtu 1691 -> local mtu 1691
[00:00:34.199] LOG -- hci.c.3397: Create_connection to 22:22:22:6A:A7:7B
[00:00:34.205] LOG -- hci.c.184: create_connection_for_addr 22:22:22:6A:A7:7B, type ff
[00:00:34.212] LOG -- hci.c.3409: conn state 0
[00:00:34.217] CMD => 05 04 0D 7B A7 6A 22 22 22 18 CC 00 00 00 00 01 
 - Create AVDTP Source connection to addr 22:22:22:6A:A7:7B, cid 0x01.
[00:00:34.229] EVT <= 6E 00 
[00:00:34.232] EVT <= 0F 04 00 05 05 04 
[00:00:35.670] EVT <= 03 0B 00 81 00 7B A7 6A 22 22 22 01 00 
[00:00:35.670] LOG -- hci.c.1905: Connection_complete (status=0) 22:22:22:6A:A7:7B
[00:00:35.672] LOG -- hci.c.1918: New connection: handle 129, 22:22:22:6A:A7:7B
[00:00:35.679] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 1
[00:00:35.685] EVT <= 61 01 01 
[00:00:35.688] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:35.696] CMD => 1B 04 02 81 00 
[00:00:35.699] EVT <= 0F 04 00 05 1B 04 
[00:00:35.703] EVT <= 0B 0B 00 81 00 BF 3A 85 FA 88 BD 59 87 
[00:00:35.708] LOG -- hci.c.1984: HCI_EVENT_READ_REMOTE_SUPPORTED_FEATURES_COMPLETE, bonding flags 6, eSCO 1
[00:00:35.718] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 1 = 1
[00:00:35.728] EVT <= 6E 00 
[00:00:35.730] ACL => 81 00 0C 00 08 00 01 00 02 04 04 00 01 00 42 00 
[00:00:35.737] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x42
[00:00:35.743] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x42
[00:00:35.749] EVT <= 6E 00 
[00:00:35.757] EVT <= 13 05 01 81 00 01 00 
[00:00:35.835] EVT <= 1B 03 81 00 05 
[00:00:35.839] ACL <= 81 20 10 00 0C 00 01 00 03 04 08 00 4C 00 42 00 00 00 00 00 
[00:00:35.839] LOG -- l2cap.c.2421: L2CAP signaling handler code 3, state 10
[00:00:35.844] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x42
[00:00:35.850] ACL => 81 00 10 00 0C 00 01 00 04 05 08 00 4C 00 00 00 01 02 9B 06 
[00:00:35.857] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x42
[00:00:35.863] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x42
[00:00:35.870] EVT <= 13 05 01 81 00 01 00 
[00:00:35.874] EVT <= 6E 00 
[00:00:35.876] CMD => 35 0C 05 01 81 00 01 00 
[00:00:35.880] EVT <= 6E 00 
[00:00:35.915] ACL <= 81 20 12 00 0E 00 01 00 05 05 0A 00 42 00 00 00 00 00 01 02 A0 02 
[00:00:35.916] LOG -- l2cap.c.2421: L2CAP signaling handler code 5, state 11
[00:00:35.919] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x42
[00:00:35.925] LOG -- l2cap.c.2353: l2cap_signaling_handle_configure_response
[00:00:35.932] CMD => 35 0C 05 01 81 00 01 00 
[00:00:35.936] ACL <= 81 20 10 00 0C 00 01 00 04 13 08 00 42 00 00 00 01 02 A0 02 
[00:00:35.943] LOG -- l2cap.c.2421: L2CAP signaling handler code 4, state 11
[00:00:35.950] LOG -- l2cap.c.2276: Remote MTU 672
[00:00:35.955] EVT <= 6E 00 
[00:00:35.957] ACL => 81 00 12 00 0E 00 01 00 05 13 0A 00 4C 00 00 00 00 00 01 02 A0 02 
[00:00:35.965] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr 22:22:22:6A:A7:7B handle 0x81 psm 0x1 local_cid 0x42 remote_cid 0x4c local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:35.982] EVT <= 70 16 00 7B A7 6A 22 22 22 81 00 01 00 42 00 4C 00 9B 06 A0 02 00 00 00 
[00:00:35.991] EVT <= 13 05 01 81 00 01 00 
[00:00:35.994] EVT <= 6E 00 
[00:00:35.997] EVT <= 78 02 42 00 
[00:00:36.000] ACL => 81 00 18 00 14 00 4C 00 06 00 02 00 0F 35 03 19 00 19 06 9B 35 05 0A 00 01 FF FF 00 
[00:00:36.010] EVT <= 6E 00 
[00:00:36.012] CMD => 35 0C 05 01 81 00 01 00 
[00:00:36.016] EVT <= 6E 00 
[00:00:36.037] EVT <= 13 05 01 81 00 01 00 
[00:00:36.090] ACL <= 81 20 36 00 3E 00 42 00 07 00 02 00 39 00 36 36 00 33 36 00 30 09 00 01 35 03 19 11 0B 09 00 04 35 10 35 06 19 01 00 09 00 19 35 06 19 00 19 09 01 03 09 00 09 35 08 35 06 19 
[00:00:36.097] CMD => 35 0C 05 01 81 00 01 00 
[00:00:36.101] ACL <= 81 10 0C 00 11 0D 09 01 03 09 03 11 09 00 01 00 
[00:00:36.108] LOG -- l2cap.c.1805: L2CAP_DISCONNECT local_cid 0x42 reason 0x0
[00:00:36.114] EVT <= 6E 00 
[00:00:36.117] ACL => 81 00 0C 00 08 00 01 00 06 06 04 00 4C 00 42 00 
[00:00:36.123] EVT <= 6E 00 
[00:00:36.126] CMD => 35 0C 05 01 81 00 01 00 
[00:00:36.130] EVT <= 6E 00 
[00:00:36.151] EVT <= 13 05 01 81 00 01 00 
[00:00:36.229] ACL <= 81 20 0C 00 08 00 01 00 07 06 04 00 4C 00 42 00 
[00:00:36.229] LOG -- l2cap.c.2421: L2CAP signaling handler code 7, state 13
[00:00:36.231] LOG -- l2cap.c.922: L2CAP_EVENT_CHANNEL_CLOSED local_cid 0x42
[00:00:36.238] EVT <= 71 02 42 00 
[00:00:36.241] LOG -- sdp_client.c.472: SDP Client disconnected.
[00:00:36.247] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x19 mtu 1691 -> local mtu 1691
[00:00:36.257] LOG -- l2cap.c.1790: l2cap_create_channel, hci connection already exists
[00:00:36.265] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:36.272] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 25 = 0
[00:00:36.282] LOG -- hci.c.3889: gap_request_security_level 2, current level 0
[00:00:36.289] CMD => 35 0C 05 01 81 00 01 00 
[00:00:36.293] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x42
[00:00:36.299] EVT <= 6E 00 
[00:00:36.302] CMD => 11 04 02 81 00 
[00:00:36.305] EVT <= 0F 04 00 05 11 04 
[00:00:36.309] EVT <= 17 06 7B A7 6A 22 22 22 
[00:00:36.313] LOG -- hci.c.1991: HCI_EVENT_LINK_KEY_REQUEST
[00:00:36.318] EVT <= 6E 00 
[00:00:36.321] LOG -- hci.c.3164: responding to link key request
[00:00:36.327] LOG -- btstack_tlv_esp32.c.54: tag 42544c00 -> 42544C00
[00:00:36.333] LOG -- btstack_tlv_esp32.c.68: read tag 42544C00
[00:00:36.339] LOG -- btstack_tlv_esp32.c.54: tag 42544c01 -> 42544C01
[00:00:36.345] LOG -- btstack_tlv_esp32.c.68: read tag 42544C01
[00:00:36.351] LOG -- btstack_tlv_esp32.c.54: tag 42544c02 -> 42544C02
[00:00:36.357] LOG -- btstack_tlv_esp32.c.68: read tag 42544C02
[00:00:36.362] LOG -- btstack_tlv_esp32.c.54: tag 42544c03 -> 42544C03
[00:00:36.369] LOG -- btstack_tlv_esp32.c.68: read tag 42544C03
[00:00:36.374] LOG -- btstack_tlv_esp32.c.54: tag 42544c04 -> 42544C04
[00:00:36.381] LOG -- btstack_tlv_esp32.c.68: read tag 42544C04
[00:00:36.386] LOG -- btstack_tlv_esp32.c.54: tag 42544c05 -> 42544C05
[00:00:36.392] LOG -- btstack_tlv_esp32.c.68: read tag 42544C05
[00:00:36.398] LOG -- btstack_tlv_esp32.c.54: tag 42544c06 -> 42544C06
[00:00:36.404] LOG -- btstack_tlv_esp32.c.68: read tag 42544C06
[00:00:36.410] LOG -- btstack_tlv_esp32.c.54: tag 42544c07 -> 42544C07
[00:00:36.416] LOG -- btstack_tlv_esp32.c.68: read tag 42544C07
[00:00:36.422] LOG -- btstack_tlv_esp32.c.54: tag 42544c08 -> 42544C08
[00:00:36.428] LOG -- btstack_tlv_esp32.c.68: read tag 42544C08
[00:00:36.434] LOG -- btstack_tlv_esp32.c.54: tag 42544c09 -> 42544C09
[00:00:36.440] LOG -- btstack_tlv_esp32.c.68: read tag 42544C09
[00:00:36.446] LOG -- btstack_tlv_esp32.c.54: tag 42544c0a -> 42544C0A
[00:00:36.452] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0A
[00:00:36.458] LOG -- btstack_tlv_esp32.c.54: tag 42544c0b -> 42544C0B
[00:00:36.464] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0B
[00:00:36.469] LOG -- btstack_tlv_esp32.c.54: tag 42544c0c -> 42544C0C
[00:00:36.476] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0C
[00:00:36.481] LOG -- btstack_tlv_esp32.c.54: tag 42544c0d -> 42544C0D
[00:00:36.488] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0D
[00:00:36.493] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0d, addr 30:21:8A:0B:48:C1
[00:00:36.501] LOG -- btstack_tlv_esp32.c.54: tag 42544c0e -> 42544C0E
[00:00:36.507] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0E
[00:00:36.513] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0e, addr 22:22:22:6A:A7:7B
[00:00:36.521] CMD => 0B 04 16 7B A7 6A 22 22 22 1D 66 C0 C7 86 1D EB 9F 11 5B E4 55 08 43 DE 4E 
[00:00:36.530] EVT <= 0E 0A 05 0B 04 00 7B A7 6A 22 22 22 
[00:00:36.535] EVT <= 6E 00 
[00:00:36.645] EVT <= 06 03 05 81 00 
[00:00:36.646] LOG -- hci.c.3787: hci_emit_security_level 0 for handle 81
[00:00:36.646] EVT <= E0 03 81 00 00 
[00:00:36.648] LOG -- l2cap.c.2104: l2cap - security level update
[00:00:36.654] LOG -- l2cap.c.2113: channel state 6: actual 0 >= required 2?
[00:00:36.660] CMD => 06 04 03 81 00 05 
[00:00:36.664] EVT <= 0F 04 00 05 06 04 
[00:00:36.668] EVT <= 6E 00 
[00:00:36.742] EVT <= 05 04 00 81 00 16 
[00:00:36.742] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x43
[00:00:36.742] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x6a addr 22:22:22:6A:A7:7B handle 0x81 psm 0x19 local_cid 0x43 remote_cid 0x0 local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:36.758] EVT <= 70 16 6A 7B A7 6A 22 22 22 81 00 19 00 43 00 00 00 9B 06 A0 02 00 00 00 
[00:00:36.766] LOG -- avdtp.c.542: L2CAP_EVENT_CHANNEL_OPENED: status 106, cid 0x43 , signaling connection 0x3ffd5000 

[00:00:36.777] LOG -- avdtp.c.551: L2CAP connection to 22:22:22:6A:A7:7B failed. status code 0x6a
[00:00:36.785] LOG -- a2dp_source.c.185: AVDTP_SUBEVENT_SIGNALING_CONNECTION failed status 106 ---
A2DP: Stream establishment failed: status 0x6a.
[00:00:36.798] LOG -- hci.c.826: Connection closed: handle 0x81, 22:22:22:6A:A7:7B
[00:00:36.805] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 0
[00:00:36.812] EVT <= 61 01 00 
[00:00:48.115] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x1 mtu 1691 -> local mtu 1691
[00:00:48.116] LOG -- hci.c.3397: Create_connection to 22:22:22:6A:A7:7B
[00:00:48.121] LOG -- hci.c.184: create_connection_for_addr 22:22:22:6A:A7:7B, type ff
[00:00:48.129] LOG -- hci.c.3409: conn state 0
[00:00:48.133] CMD => 05 04 0D 7B A7 6A 22 22 22 18 CC 00 00 00 00 01 
 - Create AVDTP Source connection to addr 22:22:22:6A:A7:7B, cid 0x01.
[00:00:48.145] EVT <= 6E 00 
[00:00:48.148] EVT <= 0F 04 00 05 05 04 
[00:00:49.206] EVT <= 03 0B 00 81 00 7B A7 6A 22 22 22 01 00 
[00:00:49.207] LOG -- hci.c.1905: Connection_complete (status=0) 22:22:22:6A:A7:7B
[00:00:49.208] LOG -- hci.c.1918: New connection: handle 129, 22:22:22:6A:A7:7B
[00:00:49.215] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 1
[00:00:49.222] EVT <= 61 01 01 
[00:00:49.225] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:49.232] CMD => 1B 04 02 81 00 
[00:00:49.236] EVT <= 0F 04 00 05 1B 04 
[00:00:49.239] EVT <= 0B 0B 00 81 00 BF 3A 85 FA 88 BD 59 87 
[00:00:49.244] LOG -- hci.c.1984: HCI_EVENT_READ_REMOTE_SUPPORTED_FEATURES_COMPLETE, bonding flags 6, eSCO 1
[00:00:49.254] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 1 = 1
[00:00:49.264] EVT <= 6E 00 
[00:00:49.267] ACL => 81 00 0C 00 08 00 01 00 02 07 04 00 01 00 44 00 
[00:00:49.273] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x44
[00:00:49.279] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x44
[00:00:49.285] EVT <= 6E 00 
[00:00:49.294] EVT <= 13 05 01 81 00 01 00 
[00:00:49.371] EVT <= 1B 03 81 00 05 
[00:00:49.375] ACL <= 81 20 10 00 0C 00 01 00 03 07 08 00 4D 00 44 00 00 00 00 00 
[00:00:49.376] LOG -- l2cap.c.2421: L2CAP signaling handler code 3, state 10
[00:00:49.380] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x44
[00:00:49.386] ACL => 81 00 10 00 0C 00 01 00 04 08 08 00 4D 00 00 00 01 02 9B 06 
[00:00:49.394] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x44
[00:00:49.400] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x44
[00:00:49.406] EVT <= 13 05 01 81 00 01 00 
[00:00:49.410] EVT <= 6E 00 
[00:00:49.412] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.417] EVT <= 6E 00 
[00:00:49.451] ACL <= 81 20 12 00 0E 00 01 00 05 08 0A 00 44 00 00 00 00 00 01 02 A0 02 
[00:00:49.452] LOG -- l2cap.c.2421: L2CAP signaling handler code 5, state 11
[00:00:49.455] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x44
[00:00:49.461] LOG -- l2cap.c.2353: l2cap_signaling_handle_configure_response
[00:00:49.468] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.472] EVT <= 6E 00 
[00:00:49.481] ACL <= 81 20 10 00 0C 00 01 00 04 14 08 00 44 00 00 00 01 02 A0 02 
[00:00:49.482] LOG -- l2cap.c.2421: L2CAP signaling handler code 4, state 11
[00:00:49.489] LOG -- l2cap.c.2276: Remote MTU 672
[00:00:49.494] ACL => 81 00 12 00 0E 00 01 00 05 14 0A 00 4D 00 00 00 00 00 01 02 A0 02 
[00:00:49.501] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr 22:22:22:6A:A7:7B handle 0x81 psm 0x1 local_cid 0x44 remote_cid 0x4d local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:49.518] EVT <= 70 16 00 7B A7 6A 22 22 22 81 00 01 00 44 00 4D 00 9B 06 A0 02 00 00 00 
[00:00:49.527] EVT <= 13 05 01 81 00 01 00 
[00:00:49.531] EVT <= 6E 00 
[00:00:49.533] EVT <= 78 02 44 00 
[00:00:49.536] ACL => 81 00 18 00 14 00 4D 00 06 00 03 00 0F 35 03 19 00 19 06 9B 35 05 0A 00 01 FF FF 00 
[00:00:49.546] EVT <= 6E 00 
[00:00:49.548] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.553] EVT <= 6E 00 
[00:00:49.574] EVT <= 13 05 01 81 00 01 00 
[00:00:49.626] ACL <= 81 20 36 00 3E 00 44 00 07 00 03 00 39 00 36 36 00 33 36 00 30 09 00 01 35 03 19 11 0B 09 00 04 35 10 35 06 19 01 00 09 00 19 35 06 19 00 19 09 01 03 09 00 09 35 08 35 06 19 
[00:00:49.633] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.637] ACL <= 81 10 0C 00 11 0D 09 01 03 09 03 11 09 00 01 00 
[00:00:49.644] LOG -- l2cap.c.1805: L2CAP_DISCONNECT local_cid 0x44 reason 0x0
[00:00:49.651] EVT <= 6E 00 
[00:00:49.653] ACL => 81 00 0C 00 08 00 01 00 06 09 04 00 4D 00 44 00 
[00:00:49.659] EVT <= 6E 00 
[00:00:49.662] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.666] EVT <= 13 05 01 81 00 01 00 
[00:00:49.670] EVT <= 6E 00 
[00:00:49.741] ACL <= 81 20 0C 00 08 00 01 00 07 09 04 00 4D 00 44 00 
[00:00:49.742] LOG -- l2cap.c.2421: L2CAP signaling handler code 7, state 13
[00:00:49.744] LOG -- l2cap.c.922: L2CAP_EVENT_CHANNEL_CLOSED local_cid 0x44
[00:00:49.750] EVT <= 71 02 44 00 
[00:00:49.754] LOG -- sdp_client.c.472: SDP Client disconnected.
[00:00:49.759] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x19 mtu 1691 -> local mtu 1691
[00:00:49.769] LOG -- l2cap.c.1790: l2cap_create_channel, hci connection already exists
[00:00:49.777] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:00:49.784] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 25 = 0
[00:00:49.794] LOG -- hci.c.3889: gap_request_security_level 2, current level 0
[00:00:49.801] CMD => 35 0C 05 01 81 00 01 00 
[00:00:49.806] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x44
[00:00:49.812] EVT <= 6E 00 
[00:00:49.815] CMD => 11 04 02 81 00 
[00:00:49.818] EVT <= 0F 04 00 05 11 04 
[00:00:49.821] EVT <= 17 06 7B A7 6A 22 22 22 
[00:00:49.826] LOG -- hci.c.1991: HCI_EVENT_LINK_KEY_REQUEST
[00:00:49.831] EVT <= 6E 00 
[00:00:49.834] LOG -- hci.c.3164: responding to link key request
[00:00:49.839] LOG -- btstack_tlv_esp32.c.54: tag 42544c00 -> 42544C00
[00:00:49.846] LOG -- btstack_tlv_esp32.c.68: read tag 42544C00
[00:00:49.851] LOG -- btstack_tlv_esp32.c.54: tag 42544c01 -> 42544C01
[00:00:49.857] LOG -- btstack_tlv_esp32.c.68: read tag 42544C01
[00:00:49.863] LOG -- btstack_tlv_esp32.c.54: tag 42544c02 -> 42544C02
[00:00:49.869] LOG -- btstack_tlv_esp32.c.68: read tag 42544C02
[00:00:49.875] LOG -- btstack_tlv_esp32.c.54: tag 42544c03 -> 42544C03
[00:00:49.881] LOG -- btstack_tlv_esp32.c.68: read tag 42544C03
[00:00:49.887] LOG -- btstack_tlv_esp32.c.54: tag 42544c04 -> 42544C04
[00:00:49.893] LOG -- btstack_tlv_esp32.c.68: read tag 42544C04
[00:00:49.899] LOG -- btstack_tlv_esp32.c.54: tag 42544c05 -> 42544C05
[00:00:49.905] LOG -- btstack_tlv_esp32.c.68: read tag 42544C05
[00:00:49.911] LOG -- btstack_tlv_esp32.c.54: tag 42544c06 -> 42544C06
[00:00:49.917] LOG -- btstack_tlv_esp32.c.68: read tag 42544C06
[00:00:49.923] LOG -- btstack_tlv_esp32.c.54: tag 42544c07 -> 42544C07
[00:00:49.929] LOG -- btstack_tlv_esp32.c.68: read tag 42544C07
[00:00:49.934] LOG -- btstack_tlv_esp32.c.54: tag 42544c08 -> 42544C08
[00:00:49.941] LOG -- btstack_tlv_esp32.c.68: read tag 42544C08
[00:00:49.946] LOG -- btstack_tlv_esp32.c.54: tag 42544c09 -> 42544C09
[00:00:49.953] LOG -- btstack_tlv_esp32.c.68: read tag 42544C09
[00:00:49.958] LOG -- btstack_tlv_esp32.c.54: tag 42544c0a -> 42544C0A
[00:00:49.964] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0A
[00:00:49.970] LOG -- btstack_tlv_esp32.c.54: tag 42544c0b -> 42544C0B
[00:00:49.976] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0B
[00:00:49.982] LOG -- btstack_tlv_esp32.c.54: tag 42544c0c -> 42544C0C
[00:00:49.988] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0C
[00:00:49.994] LOG -- btstack_tlv_esp32.c.54: tag 42544c0d -> 42544C0D
[00:00:50.000] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0D
[00:00:50.006] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0d, addr 30:21:8A:0B:48:C1
[00:00:50.014] LOG -- btstack_tlv_esp32.c.54: tag 42544c0e -> 42544C0E
[00:00:50.020] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0E
[00:00:50.026] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0e, addr 22:22:22:6A:A7:7B
[00:00:50.033] CMD => 0B 04 16 7B A7 6A 22 22 22 1D 66 C0 C7 86 1D EB 9F 11 5B E4 55 08 43 DE 4E 
[00:00:50.042] EVT <= 0E 0A 05 0B 04 00 7B A7 6A 22 22 22 
[00:00:50.047] EVT <= 6E 00 
[00:00:50.157] EVT <= 06 03 05 81 00 
[00:00:50.157] LOG -- hci.c.3787: hci_emit_security_level 0 for handle 81
[00:00:50.157] EVT <= E0 03 81 00 00 
[00:00:50.159] LOG -- l2cap.c.2104: l2cap - security level update
[00:00:50.165] LOG -- l2cap.c.2113: channel state 6: actual 0 >= required 2?
[00:00:50.172] CMD => 06 04 03 81 00 05 
[00:00:50.175] EVT <= 0F 04 00 05 06 04 
[00:00:50.179] EVT <= 6E 00 
[00:00:50.252] EVT <= 05 04 00 81 00 16 
[00:00:50.252] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x45
[00:00:50.252] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x6a addr 22:22:22:6A:A7:7B handle 0x81 psm 0x19 local_cid 0x45 remote_cid 0x0 local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:00:50.268] EVT <= 70 16 6A 7B A7 6A 22 22 22 81 00 19 00 45 00 00 00 9B 06 A0 02 00 00 00 
[00:00:50.276] LOG -- avdtp.c.542: L2CAP_EVENT_CHANNEL_OPENED: status 106, cid 0x45 , signaling connection 0x3ffd5000 

[00:00:50.287] LOG -- avdtp.c.551: L2CAP connection to 22:22:22:6A:A7:7B failed. status code 0x6a
[00:00:50.295] LOG -- a2dp_source.c.185: AVDTP_SUBEVENT_SIGNALING_CONNECTION failed status 106 ---
A2DP: Stream establishment failed: status 0x6a.
[00:00:50.308] LOG -- hci.c.826: Connection closed: handle 0x81, 22:22:22:6A:A7:7B
[00:00:50.315] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 0
[00:00:50.322] EVT <= 61 01 00 
[00:01:06.588] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x1 mtu 1691 -> local mtu 1691
[00:01:06.588] LOG -- hci.c.3397: Create_connection to 22:22:22:6A:A7:7B
[00:01:06.593] LOG -- hci.c.184: create_connection_for_addr 22:22:22:6A:A7:7B, type ff
[00:01:06.601] LOG -- hci.c.3409: conn state 0
[00:01:06.605] CMD => 05 04 0D 7B A7 6A 22 22 22 18 CC 00 00 00 00 01 
 - Create AVDTP Source connection to addr 22:22:22:6A:A7:7B, cid 0x01.
[00:01:06.618] EVT <= 6E 00 
[00:01:06.620] EVT <= 0F 04 00 05 05 04 
[00:01:09.223] EVT <= 03 0B 00 81 00 7B A7 6A 22 22 22 01 00 
[00:01:09.223] LOG -- hci.c.1905: Connection_complete (status=0) 22:22:22:6A:A7:7B
[00:01:09.225] LOG -- hci.c.1918: New connection: handle 129, 22:22:22:6A:A7:7B
[00:01:09.232] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 1
[00:01:09.238] EVT <= 61 01 01 
[00:01:09.241] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:01:09.248] CMD => 1B 04 02 81 00 
[00:01:09.252] EVT <= 0F 04 00 05 1B 04 
[00:01:09.255] EVT <= 0B 0B 00 81 00 BF 3A 85 FA 88 BD 59 87 
[00:01:09.261] LOG -- hci.c.1984: HCI_EVENT_READ_REMOTE_SUPPORTED_FEATURES_COMPLETE, bonding flags 6, eSCO 1
[00:01:09.270] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 1 = 1
[00:01:09.280] EVT <= 6E 00 
[00:01:09.283] ACL => 81 00 0C 00 08 00 01 00 02 0A 04 00 01 00 46 00 
[00:01:09.289] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x46
[00:01:09.295] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x46
[00:01:09.301] EVT <= 6E 00 
[00:01:09.310] EVT <= 13 05 01 81 00 01 00 
[00:01:09.388] EVT <= 1B 03 81 00 05 
[00:01:09.391] ACL <= 81 20 10 00 0C 00 01 00 03 0A 08 00 40 00 46 00 00 00 00 00 
[00:01:09.392] LOG -- l2cap.c.2421: L2CAP signaling handler code 3, state 10
[00:01:09.396] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x46
[00:01:09.403] ACL => 81 00 10 00 0C 00 01 00 04 0B 08 00 40 00 00 00 01 02 9B 06 
[00:01:09.410] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x46
[00:01:09.416] LOG -- l2cap.c.1034: l2cap_start_rtx for local cid 0x46
[00:01:09.422] EVT <= 13 05 01 81 00 01 00 
[00:01:09.426] EVT <= 6E 00 
[00:01:09.429] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.433] EVT <= 6E 00 
[00:01:09.493] ACL <= 81 20 12 00 0E 00 01 00 05 0B 0A 00 46 00 00 00 00 00 01 02 A0 02 
[00:01:09.493] LOG -- l2cap.c.2421: L2CAP signaling handler code 5, state 11
[00:01:09.496] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x46
[00:01:09.503] LOG -- l2cap.c.2353: l2cap_signaling_handle_configure_response
[00:01:09.509] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.514] ACL <= 81 20 10 00 0C 00 01 00 04 01 08 00 46 00 00 00 01 02 A0 02 
[00:01:09.521] LOG -- l2cap.c.2421: L2CAP signaling handler code 4, state 11
[00:01:09.528] LOG -- l2cap.c.2276: Remote MTU 672
[00:01:09.532] EVT <= 6E 00 
[00:01:09.535] ACL => 81 00 12 00 0E 00 01 00 05 01 0A 00 40 00 00 00 00 00 01 02 A0 02 
[00:01:09.543] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr 22:22:22:6A:A7:7B handle 0x81 psm 0x1 local_cid 0x46 remote_cid 0x40 local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:01:09.560] EVT <= 70 16 00 7B A7 6A 22 22 22 81 00 01 00 46 00 40 00 9B 06 A0 02 00 00 00 
[00:01:09.568] EVT <= 13 05 01 81 00 01 00 
[00:01:09.572] EVT <= 6E 00 
[00:01:09.575] EVT <= 78 02 46 00 
[00:01:09.578] ACL => 81 00 18 00 14 00 40 00 06 00 04 00 0F 35 03 19 00 19 06 9B 35 05 0A 00 01 FF FF 00 
[00:01:09.587] EVT <= 6E 00 
[00:01:09.590] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.594] EVT <= 6E 00 
[00:01:09.616] EVT <= 13 05 01 81 00 01 00 
[00:01:09.669] ACL <= 81 20 36 00 3E 00 46 00 07 00 04 00 39 00 36 36 00 33 36 00 30 09 00 01 35 03 19 11 0B 09 00 04 35 10 35 06 19 01 00 09 00 19 35 06 19 00 19 09 01 03 09 00 09 35 08 35 06 19 
[00:01:09.676] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.680] ACL <= 81 10 0C 00 11 0D 09 01 03 09 03 11 09 00 01 00 
[00:01:09.686] LOG -- l2cap.c.1805: L2CAP_DISCONNECT local_cid 0x46 reason 0x0
[00:01:09.693] EVT <= 6E 00 
[00:01:09.696] ACL => 81 00 0C 00 08 00 01 00 06 0C 04 00 40 00 46 00 
[00:01:09.702] EVT <= 6E 00 
[00:01:09.704] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.709] EVT <= 6E 00 
[00:01:09.730] EVT <= 13 05 01 81 00 01 00 
[00:01:09.808] ACL <= 81 20 0C 00 08 00 01 00 07 0C 04 00 40 00 46 00 
[00:01:09.808] LOG -- l2cap.c.2421: L2CAP signaling handler code 7, state 13
[00:01:09.810] LOG -- l2cap.c.922: L2CAP_EVENT_CHANNEL_CLOSED local_cid 0x46
[00:01:09.817] EVT <= 71 02 46 00 
[00:01:09.820] LOG -- sdp_client.c.472: SDP Client disconnected.
[00:01:09.826] LOG -- l2cap.c.1768: L2CAP_CREATE_CHANNEL addr 22:22:22:6A:A7:7B psm 0x19 mtu 1691 -> local mtu 1691
[00:01:09.836] LOG -- l2cap.c.1790: l2cap_create_channel, hci connection already exists
[00:01:09.843] LOG -- l2cap.c.1677: l2cap_handle_connection_complete expected state
[00:01:09.851] LOG -- l2cap.c.1705: l2cap received remote supported features, sec_level_0_allowed for psm 25 = 0
[00:01:09.861] LOG -- hci.c.3889: gap_request_security_level 2, current level 0
[00:01:09.868] CMD => 35 0C 05 01 81 00 01 00 
[00:01:09.872] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x46
[00:01:09.878] EVT <= 6E 00 
[00:01:09.881] CMD => 11 04 02 81 00 
[00:01:09.884] EVT <= 0F 04 00 05 11 04 
[00:01:09.888] EVT <= 17 06 7B A7 6A 22 22 22 
[00:01:09.892] LOG -- hci.c.1991: HCI_EVENT_LINK_KEY_REQUEST
[00:01:09.897] EVT <= 6E 00 
[00:01:09.900] LOG -- hci.c.3164: responding to link key request
[00:01:09.906] LOG -- btstack_tlv_esp32.c.54: tag 42544c00 -> 42544C00
[00:01:09.912] LOG -- btstack_tlv_esp32.c.68: read tag 42544C00
[00:01:09.918] LOG -- btstack_tlv_esp32.c.54: tag 42544c01 -> 42544C01
[00:01:09.924] LOG -- btstack_tlv_esp32.c.68: read tag 42544C01
[00:01:09.929] LOG -- btstack_tlv_esp32.c.54: tag 42544c02 -> 42544C02
[00:01:09.936] LOG -- btstack_tlv_esp32.c.68: read tag 42544C02
[00:01:09.941] LOG -- btstack_tlv_esp32.c.54: tag 42544c03 -> 42544C03
[00:01:09.947] LOG -- btstack_tlv_esp32.c.68: read tag 42544C03
[00:01:09.953] LOG -- btstack_tlv_esp32.c.54: tag 42544c04 -> 42544C04
[00:01:09.959] LOG -- btstack_tlv_esp32.c.68: read tag 42544C04
[00:01:09.965] LOG -- btstack_tlv_esp32.c.54: tag 42544c05 -> 42544C05
[00:01:09.971] LOG -- btstack_tlv_esp32.c.68: read tag 42544C05
[00:01:09.977] LOG -- btstack_tlv_esp32.c.54: tag 42544c06 -> 42544C06
[00:01:09.983] LOG -- btstack_tlv_esp32.c.68: read tag 42544C06
[00:01:09.989] LOG -- btstack_tlv_esp32.c.54: tag 42544c07 -> 42544C07
[00:01:09.995] LOG -- btstack_tlv_esp32.c.68: read tag 42544C07
[00:01:10.001] LOG -- btstack_tlv_esp32.c.54: tag 42544c08 -> 42544C08
[00:01:10.007] LOG -- btstack_tlv_esp32.c.68: read tag 42544C08
[00:01:10.013] LOG -- btstack_tlv_esp32.c.54: tag 42544c09 -> 42544C09
[00:01:10.019] LOG -- btstack_tlv_esp32.c.68: read tag 42544C09
[00:01:10.024] LOG -- btstack_tlv_esp32.c.54: tag 42544c0a -> 42544C0A
[00:01:10.031] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0A
[00:01:10.036] LOG -- btstack_tlv_esp32.c.54: tag 42544c0b -> 42544C0B
[00:01:10.043] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0B
[00:01:10.048] LOG -- btstack_tlv_esp32.c.54: tag 42544c0c -> 42544C0C
[00:01:10.054] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0C
[00:01:10.060] LOG -- btstack_tlv_esp32.c.54: tag 42544c0d -> 42544C0D
[00:01:10.066] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0D
[00:01:10.072] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0d, addr 30:21:8A:0B:48:C1
[00:01:10.080] LOG -- btstack_tlv_esp32.c.54: tag 42544c0e -> 42544C0E
[00:01:10.086] LOG -- btstack_tlv_esp32.c.68: read tag 42544C0E
[00:01:10.092] LOG -- btstack_link_key_db_tlv.c.95: tag 42544c0e, addr 22:22:22:6A:A7:7B
[00:01:10.100] CMD => 0B 04 16 7B A7 6A 22 22 22 1D 66 C0 C7 86 1D EB 9F 11 5B E4 55 08 43 DE 4E 
[00:01:10.108] EVT <= 0E 0A 05 0B 04 00 7B A7 6A 22 22 22 
[00:01:10.114] EVT <= 6E 00 
[00:01:10.222] EVT <= 06 03 05 81 00 
[00:01:10.222] LOG -- hci.c.3787: hci_emit_security_level 0 for handle 81
[00:01:10.222] EVT <= E0 03 81 00 00 
[00:01:10.224] LOG -- l2cap.c.2104: l2cap - security level update
[00:01:10.230] LOG -- l2cap.c.2113: channel state 6: actual 0 >= required 2?
[00:01:10.237] CMD => 06 04 03 81 00 05 
[00:01:10.240] EVT <= 0F 04 00 05 06 04 
[00:01:10.244] EVT <= 6E 00 
[00:01:10.312] EVT <= 05 04 00 81 00 16 
[00:01:10.312] LOG -- l2cap.c.1028: l2cap_stop_rtx for local cid 0x47
[00:01:10.312] LOG -- l2cap.c.903: L2CAP_EVENT_CHANNEL_OPENED status 0x6a addr 22:22:22:6A:A7:7B handle 0x81 psm 0x19 local_cid 0x47 remote_cid 0x0 local_mtu 1691, remote_mtu 672, flush_timeout 0
[00:01:10.328] EVT <= 70 16 6A 7B A7 6A 22 22 22 81 00 19 00 47 00 00 00 9B 06 A0 02 00 00 00 
[00:01:10.336] LOG -- avdtp.c.542: L2CAP_EVENT_CHANNEL_OPENED: status 106, cid 0x47 , signaling connection 0x3ffd5000 

[00:01:10.347] LOG -- avdtp.c.551: L2CAP connection to 22:22:22:6A:A7:7B failed. status code 0x6a
[00:01:10.355] LOG -- a2dp_source.c.185: AVDTP_SUBEVENT_SIGNALING_CONNECTION failed status 106 ---
A2DP: Stream establishment failed: status 0x6a.
[00:01:10.368] LOG -- hci.c.826: Connection closed: handle 0x81, 22:22:22:6A:A7:7B
[00:01:10.375] LOG -- hci.c.3756: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 0
[00:01:10.382] EVT <= 61 01 00