Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

how to configure voice data which from bt to PCM format ? #238

Open
zhou88mang opened this issue Jun 5, 2023 · 10 comments
Open

how to configure voice data which from bt to PCM format ? #238

zhou88mang opened this issue Jun 5, 2023 · 10 comments

Comments

@zhou88mang
Copy link

I successfully implemented the Bluetooth function using btstack + esp-hosted, but there was one problem, i set BR/EDR Sync(SCO/eSCO) default datapath PCM .By measuring the output pin of the pcm, I found that the format of these signals is i2s format , but the signal I really want is PCM format, is there any way to configure the format of these output signals?

@mantriyogesh
Copy link
Collaborator

Thank you @zhou88mang for the details.

Which host do you use? (Linux/MCU)? Which one precisely? Which stack do you use for Bluetooth?

Can you please provide the sdkconfig , esp log and host side bluetooth logs like btmon, Bluetooth TL logs?

@zhou88mang
Copy link
Author

zhou88mang commented Jun 5, 2023

Thank you @zhou88mang for the details.

Which host do you use? (Linux/MCU)? Which one precisely? Which stack do you use for Bluetooth?

Can you please provide the sdkconfig , esp log and host side bluetooth logs like btmon, Bluetooth TL logs?

thank for your replay!
my main host chip is x2000 and platform is freertos , Bluetooth stack is btstack
this is esp log:
I (29) boot: ESP-IDF v5.0-dirty 2nd stage bootloader
I (29) boot: compile time 14:16:18
I (29) boot: chip revision: v3.1
I (32) boot_comm: chip revision: 3, min. bootloader chip revision: 0
I (40) boot.esp32: SPI Speed : 40MHz
I (44) boot.esp32: SPI Mode : DIO
I (49) boot.esp32: SPI Flash Size : 4MB
I (53) boot: Enabling RNG early entropy source...
I (59) boot: Partition Table:
I (62) boot: ## Label Usage Type ST Offset Length
I (70) boot: 0 nvs WiFi data 01 02 00009000 00004000
I (77) boot: 1 otadata OTA data 01 00 0000d000 00002000
I (84) boot: 2 phy_init RF data 01 01 0000f000 00001000
I (92) boot: 3 factory factory app 00 00 00010000 00100000
I (99) boot: 4 ota_0 OTA app 00 10 00110000 00100000
I (107) boot: 5 ota_1 OTA app 00 11 00210000 00100000
I (114) boot: End of partition table
I (119) boot: Defaulting to factory image
I (123) boot_comm: chip revision: 3, min. application chip revision: 0
I (131) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=2a990h (174480) map
I (202) esp_image: segment 1: paddr=0003a9b8 vaddr=3ffbdb60 size=05660h ( 22112) load
I (211) esp_image: segment 2: paddr=00040020 vaddr=400d0020 size=a1944h (661828) map
I (450) esp_image: segment 3: paddr=000e196c vaddr=3ffc31c0 size=00304h ( 772) load
I (451) esp_image: segment 4: paddr=000e1c78 vaddr=40080000 size=1fa88h (129672) load
I (509) esp_image: segment 5: paddr=00101708 vaddr=50000000 size=00010h ( 16) load
I (526) boot: Loaded app from partition at offset 0x10000
I (526) boot: Disabling RNG early entropy source...
I (537) cpu_start: Pro cpu up.
I (538) cpu_start: Starting app cpu, entry point is 0x4008157c
I (0) cpu_start: App cpu up.
I (554) cpu_start: Pro cpu start user code
I (554) cpu_start: cpu freq: 240000000 Hz
I (554) cpu_start: Application information:
I (559) cpu_start: Project name: network_adapter
I (564) cpu_start: App version: 1
I (569) cpu_start: Compile time: Jun 2 2023 19:19:06
I (575) cpu_start: ELF file SHA256: 8ae96442f8bd1d80...
I (581) cpu_start: ESP-IDF: v5.0-dirty
I (586) heap_init: Initializing. RAM available for dynamic allocation:
I (593) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
I (599) heap_init: At 3FFB7468 len 00000B98 (2 KiB): DRAM
I (605) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (612) heap_init: At 3FFCCB40 len 000134C0 (77 KiB): DRAM
I (618) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (624) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (630) heap_init: At 4009FA88 len 00000578 (1 KiB): IRAM
I (638) spi_flash: detected chip: generic
I (641) spi_flash: flash io: dio
I (647) cpu_start: Starting scheduler on PRO CPU.
I (0) cpu_start: Starting scheduler on APP CPU.
I (660) NETWORK_ADAPTER: *********************************************************************
I (666) NETWORK_ADAPTER: ESP-Hosted-FG Firmware version :: 0.0.5
I (674) NETWORK_ADAPTER: Transport used :: SDIO + UART
I (682) NETWORK_ADAPTER: *********************************************************************
I (690) NETWORK_ADAPTER: Supported features are:
I (694) NETWORK_ADAPTER: - WLAN over SDIO
I (698) ESP_BT: - BT/BLE
I (700) ESP_BT: - HCI Over UART
I (702) ESP_BT: - BT/BLE dual mode
I (706) NETWORK_ADAPTER: capabilities: 0x1b
I (720) BTDM_INIT: BT controller compile version [8020d24]
I (722) system_api: Base MAC address is not set
I (722) system_api: read default base MAC address from EFUSE
I (728) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07
I (1060) gpio: GPIO[18]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (1060) gpio: GPIO[19]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (1066) gpio: GPIO[23]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (1074) gpio: GPIO[22]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I2C initialized successfullyW (1088) i2s_common: APLL is occupied already, it is working at 24575996 Hz while the expected frequency is 24576000 Hz
W (1092) i2s_common: Trying to work at 24575996 Hz...
I (1098) NETWORK_ADAPTER: ESP Bluetooth MAC addr: 8:b6:1f:ef:99:46
I (1104) SDIO_SLAVE: Using SDIO interface
I (1110) wifi:wifi driver task: 3ffdf320, prio:23, stack:6656, core=1
I (1112) wifi:wifi firmware version: 0d470ef
I (1114) wifi:wifi certification version: v7.0
I (1118) wifi:config NVS flash: disabled
I (1122) wifi:config nano formating: disabled
I (1124) wifi:Init data frame dynamic rx buffer num: 32
I (1128) wifi:Init management frame dynamic rx buffer num: 32
I (1132) wifi:Init management short buffer num: 32
I (1136) wifi:Init dynamic tx buffer num: 64
I (1140) wifi:Init static rx buffer size: 1600
I (1144) wifi:Init static rx buffer num: 16
I (1146) wifi:Init dynamic rx buffer num: 32
I (1150) wifi_init: rx ba win: 6
I (1152) wifi_init: tcpip mbox: 32
I (1156) wifi_init: udp mbox: 6
I (1158) wifi_init: tcp mbox: 6
I (1162) wifi_init: tcp tx win: 5744
I (1166) wifi_init: tcp rx win: 5744
I (1168) wifi_init: tcp mss: 1440
I (1172) wifi_init: WiFi IRAM OP enabled
I (1176) wifi_init: WiFi RX IRAM OP enabled
I (1182) wifi:mode : null
I (1184) NETWORK_ADAPTER: Initial set up done

host logs :
$ hfp -start
[793.872751] [00:13:13.872] LOG -- hci.c.5085: hci_power_control: 1, current mode 0
[794.373059] [00:13:14.373] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.373410] [00:13:14.373] LOG -- hci.c.7466: BTSTACK_EVENT_STATE 1
[794.373643] [00:13:14.373] EVT <= 60 01 01
[794.373800] [00:13:14.373] CMD => 03 0C 00
$ [794.373973] [00:13:14.373] EVT <= 6E 00
[794.376685] [00:13:14.376] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.377044] [00:13:14.377] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.377398] [00:13:14.377] EVT <= 0E 04 05 03 0C 00
[794.377584] [00:13:14.377] CMD => 01 10 00
[794.377743] [00:13:14.377] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.378096] [00:13:14.378] EVT <= 6E 00
[794.380207] [00:13:14.380] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.380564] [00:13:14.380] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 12 bytes
[794.380922] [00:13:14.380] EVT <= 0E 0C 05 01 10 00 08 0E 03 08 60 00 0E 03
[794.381189] [00:13:14.381] LOG -- hci.c.2790: Manufacturer: 0x0060
[794.381418] got esp32 chipset, and manufacturer is 0x0060
[794.381620] [00:13:14.381] CMD => 14 0C 00
[794.381779] [00:13:14.381] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.382131] [00:13:14.382] EVT <= 6E 00
[794.384947] [00:13:14.384] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.385304] [00:13:14.385] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 252 bytes
[794.405066] [00:13:14.405] 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
[794.407733] [00:13:14.407] LOG -- hci.c.2645: local name:
[794.407939] [00:13:14.407] CMD => 02 10 00
[794.408098] [00:13:14.408] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.408451] [00:13:14.408] EVT <= 6E 00
[794.411266] [00:13:14.411] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.411624] [00:13:14.411] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 68 bytes
[794.415415] [00:13:14.415] 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
[794.416242] [00:13:14.416] LOG -- hci.c.2613: Command 0x00 supported 2/5
[794.416495] [00:13:14.416] LOG -- hci.c.2613: Command 0x01 supported 10/4
[794.416751] [00:13:14.416] LOG -- hci.c.2613: Command 0x02 supported 14/7
[794.417008] [00:13:14.417] LOG -- hci.c.2613: Command 0x03 supported 18/3
[794.417265] [00:13:14.417] LOG -- hci.c.2613: Command 0x04 supported 20/4
[794.417521] [00:13:14.417] LOG -- hci.c.2613: Command 0x05 supported 22/2
[794.417777] [00:13:14.417] LOG -- hci.c.2613: Command 0x06 supported 24/6
[794.418034] [00:13:14.418] LOG -- hci.c.2613: Command 0x07 supported 32/1
[794.418290] [00:13:14.418] LOG -- hci.c.2613: Command 0x08 supported 32/3
[794.418546] [00:13:14.418] LOG -- hci.c.2613: Command 0x09 supported 32/6
[794.418803] [00:13:14.418] LOG -- hci.c.2613: Command 0x0a supported 34/0
[794.419061] [00:13:14.419] LOG -- hci.c.2613: Command 0x0b supported 35/1
[794.419317] [00:13:14.419] LOG -- hci.c.2613: Command 0x0c supported 35/3
[794.419574] [00:13:14.419] LOG -- hci.c.2618: Local supported commands summary 00001fff
[794.419876] [00:13:14.419] CMD => 09 10 00
[794.420035] [00:13:14.420] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.420389] [00:13:14.420] EVT <= 6E 00
[794.421862] [00:13:14.421] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.422219] [00:13:14.422] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[794.422577] [00:13:14.422] EVT <= 0E 0A 05 09 10 00 46 99 EF 1F B6 08
[794.422825] [00:13:14.422] LOG -- hci.c.2742: Local Address, Status: 0x00: Addr: 08:B6:1F:EF:99:46
[794.423163] [00:13:14.423] CMD => 05 10 00
[794.423322] [00:13:14.423] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.423674] [00:13:14.423] EVT <= 6E 00
[794.425297] [00:13:14.425] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.425654] [00:13:14.425] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 11 bytes
[794.426012] [00:13:14.426] EVT <= 0E 0B 05 05 10 00 FD 03 FF 09 00 04 00
[794.426272] [00:13:14.426] LOG -- hci.c.2662: hci_read_buffer_size: ACL size module 1021 -> used 1021, count 9 / SCO size 255, count 4
[794.426730] [00:13:14.426] CMD => 03 10 00
[794.426889] [00:13:14.426] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.427241] [00:13:14.427] EVT <= 6E 00
[794.428889] [00:13:14.428] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.429247] [00:13:14.429] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 12 bytes
[794.429604] [00:13:14.429] EVT <= 0E 0C 05 03 10 00 BF EE CD FE DB FF 7B 87
[794.429871] [00:13:14.429] LOG -- hci.c.2776: Packet types cc18, eSCO 1
[794.430121] [00:13:14.430] LOG -- hci.c.2779: BR/EDR support 1, LE support 0
[794.430386] [00:13:14.430] CMD => 01 0C 08 FF FF FF FF FF FF FF 1F
[794.430625] [00:13:14.430] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.430976] [00:13:14.430] EVT <= 6E 00
[794.432623] [00:13:14.432] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.432980] [00:13:14.432] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.433334] [00:13:14.433] EVT <= 0E 04 05 01 0C 00
[794.433519] [00:13:14.433] CMD => 63 0C 08 00 00 00 02 00 00 00 00
[794.433758] [00:13:14.433] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.434110] [00:13:14.434] EVT <= 6E 00
[794.435756] [00:13:14.435] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.436114] [00:13:14.436] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.436468] [00:13:14.436] EVT <= 0E 04 05 63 0C 00
[794.436653] [00:13:14.436] CMD => 56 0C 01 01
[794.436821] [00:13:14.436] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.437173] [00:13:14.437] EVT <= 6E 00
[794.438585] [00:13:14.438] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.438942] [00:13:14.438] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.439296] [00:13:14.439] EVT <= 0E 04 05 56 0C 00
[794.439481] [00:13:14.439] CMD => 45 0C 01 02
[794.439649] [00:13:14.439] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.440002] [00:13:14.440] EVT <= 6E 00
[794.441412] [00:13:14.441] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.441770] [00:13:14.441] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.442123] [00:13:14.442] EVT <= 0E 04 05 45 0C 00
[794.442308] [00:13:14.442] CMD => 7A 0C 01 01
[794.442477] [00:13:14.442] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.442829] [00:13:14.442] EVT <= 6E 00
[794.444241] [00:13:14.444] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.444598] [00:13:14.444] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.444952] [00:13:14.444] EVT <= 0E 04 05 7A 0C 00
[794.445137] [00:13:14.445] CMD => 24 0C 03 0C 02 7A
[794.445325] [00:13:14.445] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.445678] [00:13:14.445] EVT <= 6E 00
[794.447069] [00:13:14.447] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.447426] [00:13:14.447] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.447780] [00:13:14.447] EVT <= 0E 04 05 24 0C 00
[794.447966] [00:13:14.447] CMD => 13 0C F8 48 46 50 20 41 47 20 44 65 6D 6F 20 30 38 3A 42 36 3A 31 46 3A 45 46 3A 39 39 3A 34 36 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[794.467169] [00:13:14.467] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.467521] [00:13:14.467] EVT <= 6E 00
[794.473409] [00:13:14.473] LOG -- hci_tr
[794.493443] [00:13:14.493] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.493796] [00:13:14.493] EVT <= 6E 00
[794.499103] [00:13:14.499] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.499461] [00:13:14.499] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.499814] [00:13:14.499] EVT <= 0E 04 05 52 0C 00
[794.499999] [00:13:14.499] CMD => 0F 08 02 00 00
[794.500186] [00:13:14.500] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.500538] [00:13:14.500] EVT <= 6E 00
[794.501932] [00:13:14.501] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.502289] [00:13:14.502] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.502643] [00:13:14.502] EVT <= 0E 04 05 0F 08 00
[794.502828] [00:13:14.502] CMD => 18 0C 02 00 60
[794.503007] [00:13:14.503] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.503360] [00:13:14.503] EVT <= 6E 00
[794.504760] [00:13:14.504] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.505117] [00:13:14.505] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.505470] [00:13:14.505] EVT <= 0E 04 05 18 0C 00
[794.505655] [00:13:14.505] CMD => 1A 0C 01 03
[794.505824] [00:13:14.505] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[794.506176] [00:13:14.506] EVT <= 6E 00
[794.507588] [00:13:14.507] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[794.507946] [00:13:14.507] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[794.508299] [00:13:14.508] EVT <= 0E 04 05 1A 0C 00
[794.508482] [00:13:14.508] EVT <= 66 02 01 01
[794.508650] [00:13:14.508] LOG -- hci.c.1790: hci_init_done -> HCI_STATE_WORKING
[794.508929] [00:13:14.508] LOG -- hci.c.7466: BTSTACK_EVENT_STATE 2
[794.509163] [00:13:14.509] EVT <= 60 01 02
[794.509316] BTstack up and running on 08:B6:1F:EF:99:46.
[794.509518] [00:13:14.509] LOG -- btstack_tlv_posix.c.185: open db /btstack_08-B6-1F-EF-99-46.tlv
[794.510288] [00:13:14.510] LOG -- btstack_tlv_posix.c.194: BTstack Magic Header found
[794.510594] [00:13:14.510] LOG -- btstack_tlv.c.48: TLV Instance 800fedac
[794.510848] Supported codecs: CVSD
[794.510977] [00:13:14.510] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[832.298002] [00:13:52.298] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[832.298361] [00:13:52.298] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[832.298719] [00:13:52.298] EVT <= 04 0A AE 9D 5C FA 9D E0 04 04 24 01
[832.298967] [00:13:52.298] LOG -- hci.c.3451: Connection_incoming: E0:9D:FA:5C:9D:AE, type 1
[832.299288] [00:13:52.299] LOG -- hci.c.328: create_connection_for_addr E0:9D:FA:5C:9D:AE, type fd
[832.299631] [00:13:52.299] LOG -- hci.c.6702: sending hci_accept_connection_request
[832.299918] [00:13:52.299] CMD => 09 04 07 AE 9D 5C FA 9D E0 00
[832.300156] [00:13:52.300] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[832.300510] [00:13:52.300] EVT <= 6E 00
[832.302074] [00:13:52.302] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[832.302432] [00:13:52.302] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[832.302786] [00:13:52.302] EVT <= 0F 04 00 05 09 04
[832.302975] [00:13:52.302] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[832.557910] [00:13:52.557] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[832.558268] [00:13:52.558] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 8 bytes
[832.558622] [00:13:52.558] EVT <= 12 08 00 AE 9D 5C FA 9D E0 00
[832.558852] [00:13:52.558] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[832.563331] [00:13:52.563] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[832.563688] [00:13:52.563] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 11 bytes
[832.564045] [00:13:52.564] EVT <= 03 0B 00 80 00 AE 9D 5C FA 9D E0 01 00
[832.564303] [00:13:52.564] LOG -- hci.c.3477: Connection_complete (status=0) E0:9D:FA:5C:9D:AE
[832.564631] [00:13:52.564] LOG -- hci.c.3514: New connection: handle 128, E0:9D:FA:5C:9D:AE
[832.564947] [00:13:52.564] LOG -- hci.c.7536: BTSTACK_EVENT_NR_CONNECTIONS_CHANGED 1
[832.565237] [00:13:52.565] EVT <= 61 01 01
[832.565398] [00:13:52.565] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[832.577554] [00:13:52.577] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[832.577912] [00:13:52.577] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 3 bytes
[832.578265] [00:13:52.578] EVT <= 1B 03 80 00 05
[832.578444] [00:13:52.578] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.570577] [00:13:53.570] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.570935] [00:13:53.570] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 6 bytes
[833.571289] [00:13:53.571] EVT <= 17 06 AE 9D 5C FA 9D E0
[833.571500] [00:13:53.571] LOG -- btstack_link_key_db_tlv.c.98: tag 42544c0b, addr E4:22:A5:23:A0:DB
[833.571848] [00:13:53.571] LOG -- btstack_link_key_db_tlv.c.98: tag 42544c0c, addr 74:EF:4B:AC:95:1E
[833.572195] [00:13:53.572] LOG -- btstack_link_key_db_tlv.c.98: tag 42544c0d, addr 00:15:BE:0F:11:DA
[833.572542] [00:13:53.572] LOG -- btstack_link_key_db_tlv.c.98: tag 42544c0e, addr E0:9D:FA:5C:9D:AE
[833.572891] [00:13:53.572] LOG -- hci.c.6725: responding to link key request, have link key db: 1
[833.573225] [00:13:53.573] CMD => 0B 04 16 AE 9D 5C FA 9D E0 5B 5F 4E A1 15 08 09 5E 80 B4 75 3C 63 9D F0 4F
[833.573606] [00:13:53.573] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.573958] [00:13:53.573] EVT <= 6E 00
[833.577350] [00:13:53.577] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.577707] [00:13:53.577] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[833.578066] [00:13:53.578] EVT <= 0E 0A 05 0B 04 00 AE 9D 5C FA 9D E0
[833.578316] [00:13:53.578] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.580091] [00:13:53.580] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.580448] [00:13:53.580] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 3 bytes
[833.580801] [00:13:53.580] EVT <= FF 03 03 80 00
[833.580981] [00:13:53.580] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.668932] [00:13:53.668] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.669290] [00:13:53.669] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.669643] [00:13:53.669] EVT <= 08 04 00 80 00 01
[833.669829] [00:13:53.669] CMD => 08 14 02 80 00
[833.670008] [00:13:53.670] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.670360] [00:13:53.670] EVT <= 6E 00
[833.672021] [00:13:53.672] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.672378] [00:13:53.672] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 7 bytes
[833.672732] [00:13:53.672] EVT <= 0E 07 05 08 14 00 80 00 10
[833.672949] [00:13:53.672] LOG -- hci.c.2808: Handle 0080 key Size: 16
[833.673194] [00:13:53.673] CMD => 1B 04 02 80 00
[833.673373] [00:13:53.673] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.673726] [00:13:53.673] EVT <= 6E 00
[833.676071] [00:13:53.676] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.676429] [00:13:53.676] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.676782] [00:13:53.676] EVT <= 0F 04 00 05 1B 04
[833.676972] [00:13:53.676] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.677329] [00:13:53.677] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.677686] [00:13:53.677] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 11 bytes
[833.678044] [00:13:53.678] EVT <= 0B 0B 00 80 00 BF FF FF FE 88 3D 19 86
[833.678299] [00:13:53.678] CMD => 1C 04 03 80 00 01
[833.678488] [00:13:53.678] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.678840] [00:13:53.678] EVT <= 6E 00
[833.680074] [00:13:53.680] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.680432] [00:13:53.680] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.680785] [00:13:53.680] EVT <= 0F 04 00 05 1C 04
[833.680975] [00:13:53.680] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.686099] [00:13:53.686] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.686457] [00:13:53.686] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 13 bytes
[833.686814] [00:13:53.686] EVT <= 23 0D 00 80 00 01 02 01 00 00 00 00 00 00 00
[833.687090] [00:13:53.687] CMD => 1C 04 03 80 00 02
[833.687279] [00:13:53.687] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.687631] [00:13:53.687] EVT <= 6E 00
[833.688928] [00:13:53.688] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.689285] [00:13:53.689] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.689639] [00:13:53.689] EVT <= 0F 04 00 05 1C 04
[833.689827] [00:13:53.689] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.693611] [00:13:53.693] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.693969] [00:13:53.693] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 13 bytes
[833.694327] [00:13:53.694] EVT <= 23 0D 00 80 00 02 02 00 03 00 00 00 00 00 00
[833.694604] [00:13:53.694] LOG -- hci.c.2529: Remote features 03, bonding flags 8f0
[833.694892] [00:13:53.694] CMD => 11 04 02 80 00
[833.695071] [00:13:53.695] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.695423] [00:13:53.695] EVT <= 6E 00
[833.697336] [00:13:53.697] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.697693] [00:13:53.697] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.698047] [00:13:53.698] EVT <= 0F 04 00 05 11 04
[833.698236] [00:13:53.698] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.698593] [00:13:53.698] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.698950] [00:13:53.698] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 6 bytes
[833.699304] [00:13:53.699] EVT <= 17 06 AE 9D 5C FA 9D E0
[833.699512] [00:13:53.699] LOG -- hci.c.6725: responding to link key request, have link key db: 1
[833.699846] [00:13:53.699] CMD => 0B 04 16 AE 9D 5C FA 9D E0 5B 5F 4E A1 15 08 09 5E 80 B4 75 3C 63 9D F0 4F
[833.700233] [00:13:53.700] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.700586] [00:13:53.700] EVT <= 6E 00
[833.703978] [00:13:53.703] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.704336] [00:13:53.704] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[833.704693] [00:13:53.704] EVT <= 0E 0A 05 0B 04 00 AE 9D 5C FA 9D E0
[833.704942] [00:13:53.704] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.707673] [00:13:53.707] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.708031] [00:13:53.708] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 12 bytes
[833.708388] [00:13:53.708] ACL <= 80 20 0C 00 08 00 01 00 02 02 04 00 01 00 40 00
[833.708679] [00:13:53.708] LOG -- l2cap.c.2460: create channel 80175d30, local_cid 0x0043
[833.708994] [00:13:53.708] LOG -- l2cap.c.1160: L2CAP_EVENT_INCOMING_CONNECTION addr E0:9D:FA:5C:9D:AE handle 0x80 psm 0x1 local_cid 0x43 remote_cid 0x40
[833.709514] [00:13:53.709] EVT <= 72 0E AE 9D 5C FA 9D E0 80 00 01 00 43 00 40 00
[833.709802] [00:13:53.709] LOG -- l2cap.c.3131: L2CAP_ACCEPT_CONNECTION local_cid 0x43
[833.710103] [00:13:53.710] ACL => 80 00 10 00 0C 00 01 00 03 02 08 00 43 00 40 00 00 00 00 00
[833.710434] [00:13:53.710] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.710788] [00:13:53.710] EVT <= 6E 00
[833.710934] [00:13:53.710] ACL => 80 00 10 00 0C 00 01 00 04 07 08 00 40 00 00 00 01 02 9B 06
[833.711263] [00:13:53.711] LOG -- l2cap.c.1404: l2cap_stop_rtx for local cid 0x43
[833.711546] [00:13:53.711] LOG -- l2cap.c.1441: l2cap_start_rtx for local cid 0x43
[833.711833] [00:13:53.711] EVT <= 6E 00
[833.716456] [00:13:53.716] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.716815] [00:13:53.716] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.717169] [00:13:53.717] EVT <= 13 05 01 80 00 01 00
[833.717369] [00:13:53.717] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.719370] [00:13:53.719] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.719728] [00:13:53.719] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.720081] [00:13:53.720] EVT <= 13 05 01 80 00 01 00
[833.720281] [00:13:53.720] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.795412] [00:13:53.795] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.795769] [00:13:53.795] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 3 bytes
[833.796123] [00:13:53.796] EVT <= 06 03 00 80 00
[833.796301] [00:13:53.796] LOG -- hci.c.7567: hci_emit_security_level 2 for handle 80
[833.796594] [00:13:53.796] EVT <= D8 03 80 00 02
[833.796771] [00:13:53.796] LOG -- l2cap.c.2833: security level update for handle 0x0080
[833.797077] [00:13:53.797] LOG -- l2cap.c.2853: channel 80175d30, cid 0043 - state 11: actual 2 >= required 0?
[833.797455] [00:13:53.797] ACL => 80 00 0A 00 06 00 01 00 0A 08 02 00 02 00
[833.797727] [00:13:53.797] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.798081] [00:13:53.798] EVT <= 6E 00
[833.798231] [00:13:53.798] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.798589] [00:13:53.798] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 16 bytes
[833.799449] [00:13:53.799] ACL <= 80 20 10 00 0C 00 01 00 04 03 08 00 43 00 00 00 01 02 00 02
[833.799777] [00:13:53.799] LOG -- l2cap.c.3359: L2CAP signaling handler code 4, state 11
[833.800091] [00:13:53.800] LOG -- l2cap.c.3187: Remote MTU 512
[833.800310] [00:13:53.800] ACL => 80 00 12 00 0E 00 01 00 05 03 0A 00 40 00 00 00 00 00 01 02 00 02
[833.800660] [00:13:53.800] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.801013] [00:13:53.801] EVT <= 6E 00
[833.856352] [00:13:53.856] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.856710] [00:13:53.856] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 3 bytes
[833.857065] [00:13:53.857] EVT <= 30 03 00 80 00
[833.857245] [00:13:53.857] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.862209] [00:13:53.862] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.862567] [00:13:53.862] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 14 bytes
[833.862924] [00:13:53.862] ACL <= 80 20 0E 00 0A 00 01 00 05 07 06 00 43 00 00 00 00 00
[833.863233] [00:13:53.863] LOG -- l2cap.c.3359: L2CAP signaling handler code 5, state 11
[833.863539] [00:13:53.863] LOG -- l2cap.c.1404: l2cap_stop_rtx for local cid 0x43
[833.863822] [00:13:53.863] LOG -- l2cap.c.3289: l2cap_signaling_handle_configure_response
[833.864140] [00:13:53.864] LOG -- l2cap.c.1131: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr E0:9D:FA:5C:9D:AE handle 0x80 psm 0x1 local_cid 0x43 remote_cid 0x40 local_mtu 1691, remote_mtu 512, flush_timeout 0
[833.864840] [00:13:53.864] EVT <= 70 18 00 AE 9D 5C FA 9D E0 80 00 01 00 43 00 40 00 9B 06 00 02 00 00 01 00 00
[833.865229] [00:13:53.865] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.865587] [00:13:53.865] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.865944] [00:13:53.865] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.866297] [00:13:53.866] EVT <= 13 05 01 80 00 01 00
[833.866497] [00:13:53.866] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.866854] [00:13:53.866] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.867212] [00:13:53.867] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.867565] [00:13:53.867] EVT <= 13 05 01 80 00 01 00
[833.867764] [00:13:53.867] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.868121] [00:13:53.868] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.868478] [00:13:53.868] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 16 bytes
[833.868836] [00:13:53.868] ACL <= 80 20 10 00 0C 00 01 00 0B 08 08 00 02 00 00 00 00 00 00 00
[833.869163] [00:13:53.869] LOG -- l2cap.c.3633: extended features mask 0x00
[833.869423] [00:13:53.869] EVT <= 78 06 80 00 00 00 00 00
[833.869632] [00:13:53.869] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.869989] [00:13:53.869] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.870347] [00:13:53.870] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 17 bytes
[833.871217] [00:13:53.871] ACL <= 80 20 11 00 0D 00 43 00 02 00 01 00 08 35 03 19 11 1F 00 08 00
[833.871559] [00:13:53.871] EVT <= 79 02 43 00
[833.871726] [00:13:53.871] ACL => 80 00 12 00 0E 00 40 00 03 00 01 00 09 00 01 00 01 00 01 00 01 00
[833.872078] [00:13:53.872] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.872431] [00:13:53.872] EVT <= 6E 00
[833.877707] [00:13:53.877] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.878067] [00:13:53.878] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.878421] [00:13:53.878] EVT <= 13 05 01 80 00 01 00
[833.878621] [00:13:53.878] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.957140] [00:13:53.957] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.957499] [00:13:53.957] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 24 bytes
[833.957857] [00:13:53.957] ACL <= 80 20 18 00 14 00 43 00 04 00 02 00 0F 00 01 00 01 03 F8 35 06 09 00 01 09 00 04 00
[833.958275] [00:13:53.958] EVT <= 79 02 43 00
[833.958441] [00:13:53.958] ACL => 80 00 2F 00 2B 00 40 00 05 00 02 00 26 00 23 36 00 20 09 00 01 36 00 06 19 11 1F 19 12 03 09 00 04 36 00 0E 36 00 03 19 01 00 36 00 05 19 00 03 08 01 00
[833.959084] [00:13:53.959] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.959438] [00:13:53.959] EVT <= 6E 00
[833.967710] [00:13:53.967] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[833.968070] [00:13:53.968] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[833.968424] [00:13:53.968] EVT <= 13 05 01 80 00 01 00
[833.968624] [00:13:53.968] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.996161] [00:13:53.996] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[833.996518] [00:13:53.996] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 12 bytes
[833.996876] [00:13:53.996] ACL <= 80 20 0C 00 08 00 01 00 02 04 04 00 03 00 41 00
[833.997166] [00:13:53.997] LOG -- l2cap.c.2460: create channel 80175d98, local_cid 0x0044
[833.997481] [00:13:53.997] LOG -- l2cap.c.1160: L2CAP_EVENT_INCOMING_CONNECTION addr E0:9D:FA:5C:9D:AE handle 0x80 psm 0x3 local_cid 0x44 remote_cid 0x41
[833.998001] [00:13:53.998] EVT <= 72 0E AE 9D 5C FA 9D E0 80 00 03 00 44 00 41 00
[833.998290] [00:13:53.998] LOG -- rfcomm.c.382: rfcomm_max_frame_size_for_l2cap_mtu: 1691 -> 1686
[833.998631] [00:13:53.998] LOG -- rfcomm.c.1074: RFCOMM incoming (l2cap_cid 0x44) => accept
[833.998948] [00:13:53.998] LOG -- l2cap.c.3131: L2CAP_ACCEPT_CONNECTION local_cid 0x44
[833.999247] [00:13:53.999] ACL => 80 00 10 00 0C 00 01 00 03 04 08 00 44 00 41 00 00 00 00 00
[833.999578] [00:13:53.999] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[833.999932] [00:13:53.999] EVT <= 6E 00
[834.000090] [00:13:54.000] ACL => 80 00 10 00 0C 00 01 00 04 09 08 00 41 00 00 00 01 02 9B 06
[834.000419] [00:13:54.000] LOG -- l2cap.c.1404: l2cap_stop_rtx for local cid 0x44
[834.000702] [00:13:54.000] LOG -- l2cap.c.1441: l2cap_start_rtx for local cid 0x44
[834.000991] [00:13:54.000] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.001345] [00:13:54.001] EVT <= 6E 00
[834.001496] [00:13:54.001] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 12 bytes
[834.001853] [00:13:54.001] ACL <= 80 20 0C 00 08 00 01 00 06 05 04 00 43 00 40 00
[834.002142] [00:13:54.002] LOG -- l2cap.c.3359: L2CAP signaling handler code 6, state 12
[834.002447] [00:13:54.002] ACL => 80 00 0C 00 08 00 01 00 07 05 04 00 43 00 40 00
[834.002736] [00:13:54.002] LOG -- l2cap.c.1188: L2CAP_EVENT_CHANNEL_CLOSED local_cid 0x43
[834.003043] [00:13:54.003] EVT <= 71 02 43 00
[834.003210] [00:13:54.003] LOG -- l2cap.c.2466: free channel 80175d30, local_cid 0x0043
[834.003513] [00:13:54.003] LOG -- l2cap.c.1404: l2cap_stop_rtx for local cid 0x43
[834.003798] [00:13:54.003] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.004150] [00:13:54.004] EVT <= 6E 00
[834.005212] [00:13:54.005] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.005569] [00:13:54.005] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.005923] [00:13:54.005] EVT <= 13 05 01 80 00 01 00
[834.006123] [00:13:54.006] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.009900] [00:13:54.009] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.010258] [00:13:54.010] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.010612] [00:13:54.010] EVT <= 13 05 01 80 00 01 00
[834.010812] [00:13:54.010] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.011169] [00:13:54.011] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.011526] [00:13:54.011] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.011879] [00:13:54.011] EVT <= 13 05 01 80 00 01 00
[834.012079] [00:13:54.012] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.012435] [00:13:54.012] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.012793] [00:13:54.012] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 16 bytes
[834.013151] [00:13:54.013] ACL <= 80 20 10 00 0C 00 01 00 04 06 08 00 44 00 00 00 01 02 A0 02
[834.013479] [00:13:54.013] LOG -- l2cap.c.3359: L2CAP signaling handler code 4, state 11
[834.013784] [00:13:54.013] LOG -- l2cap.c.3187: Remote MTU 672
[834.014003] [00:13:54.014] ACL => 80 00 12 00 0E 00 01 00 05 06 0A 00 41 00 00 00 00 00 01 02 A0 02
[834.014353] [00:13:54.014] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.014707] [00:13:54.014] EVT <= 6E 00
[834.014856] [00:13:54.014] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.015214] [00:13:54.015] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 14 bytes
[834.015572] [00:13:54.015] ACL <= 80 20 0E 00 0A 00 01 00 05 09 06 00 44 00 00 00 00 00
[834.015880] [00:13:54.015] LOG -- l2cap.c.3359: L2CAP signaling handler code 5, state 11
[834.016186] [00:13:54.016] LOG -- l2cap.c.1404: l2cap_stop_rtx for local cid 0x44
[834.016469] [00:13:54.016] LOG -- l2cap.c.3289: l2cap_signaling_handle_configure_response
[834.016787] [00:13:54.016] LOG -- l2cap.c.1131: L2CAP_EVENT_CHANNEL_OPENED status 0x0 addr E0:9D:FA:5C:9D:AE handle 0x80 psm 0x3 local_cid 0x44 remote_cid 0x41 local_mtu 1691, remote_mtu 672, flush_timeout 0
[834.017486] [00:13:54.017] EVT <= 70 18 00 AE 9D 5C FA 9D E0 80 00 03 00 44 00 41 00 9B 06 A0 02 00 00 01 00 00
[834.017872] [00:13:54.017] LOG -- rfcomm.c.1101: channel opened, status 0
[834.018130] [00:13:54.018] LOG -- rfcomm.c.382: rfcomm_max_frame_size_for_l2cap_mtu: 1691 -> 1686
[834.018472] [00:13:54.018] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.020211] [00:13:54.020] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.020569] [00:13:54.020] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.020922] [00:13:54.020] EVT <= 13 05 01 80 00 01 00
[834.021122] [00:13:54.021] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.048273] [00:13:54.048] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.048630] [00:13:54.048] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 8 bytes
[834.048984] [00:13:54.048] ACL <= 80 20 08 00 04 00 44 00 03 3F 01 1C
[834.049230] [00:13:54.049] LOG -- rfcomm.c.1219: Received SABM #0
[834.049457] [00:13:54.049] EVT <= 79 02 44 00
[834.049623] [00:13:54.049] LOG -- rfcomm.c.1364: Sending UA #0
[834.049842] [00:13:54.049] ACL => 80 00 08 00 04 00 41 00 03 73 01 D7
[834.050090] [00:13:54.050] LOG -- rfcomm.c.941: Multiplexer up and running
[834.050354] [00:13:54.050] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.050706] [00:13:54.050] EVT <= 6E 00
[834.050851] [00:13:54.050] EVT <= 79 02 44 00
[834.055212] [00:13:54.055] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.055570] [00:13:54.055] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.055924] [00:13:54.055] EVT <= 13 05 01 80 00 01 00
[834.056124] [00:13:54.056] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.084190] [00:13:54.084] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.084547] [00:13:54.084] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 19 bytes
[834.084906] [00:13:54.084] ACL <= 80 20 13 00 0F 00 44 00 03 EF 14 00 83 11 02 F0 07 00 9A 02 00 05 70
[834.085266] [00:13:54.085] LOG -- rfcomm.c.1641: Received UIH Parameter Negotiation Command for #2, credits 5
[834.085645] [00:13:54.085] LOG -- rfcomm.c.509: rfcomm_channel_create for service 80175c10, channel 1 --- list of channels:
[834.086069] [00:13:54.086] LOG -- rfcomm.c.1997: -> Inform app
[834.086291] [00:13:54.086] LOG -- rfcomm.c.248: RFCOMM_EVENT_INCOMING_CONNECTION addr E0:9D:FA:5C:9D:AE channel #1 cid 0x02
[834.086711] [00:13:54.086] EVT <= 82 0B AE 9D 5C FA 9D E0 01 02 00 80 00
[834.086971] [00:13:54.086] LOG -- hfp.c.616: Create HFP context 80175794: role 1, addr E0:9D:FA:5C:9D:AE
[834.087331] [00:13:54.087] LOG -- rfcomm.c.2628: accept cid 0x02
[834.087555] [00:13:54.087] EVT <= 79 02 44 00
[834.087724] [00:13:54.087] LOG -- rfcomm.c.2025: Sending UIH Parameter Negotiation Respond for #2
[834.088062] [00:13:54.088] ACL => 80 00 12 00 0E 00 41 00 01 EF 15 81 11 02 E0 07 00 9A 02 00 00 AA
[834.088413] [00:13:54.088] LOG -- rfcomm.c.1782: rfcomm_channel_ready_for_incoming_dlc_setup state var 00000003
[834.088798] [00:13:54.088] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.089141] [00:13:54.089] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.089494] [00:13:54.089] EVT <= 6E 00
[834.089639] [00:13:54.089] EVT <= 79 02 44 00
[834.089803] [00:13:54.089] EVT <= 89 02 02 00
[834.089969] [00:13:54.089] EVT <= 79 02 44 00
[834.093965] [00:13:54.093] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.094324] [00:13:54.094] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.094678] [00:13:54.094] EVT <= 13 05 01 80 00 01 00
[834.094879] [00:13:54.094] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.097313] [00:13:54.097] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.097671] [00:13:54.097] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 8 bytes
[834.098025] [00:13:54.098] ACL <= 80 20 08 00 04 00 44 00 0B 3F 01 59
[834.098272] [00:13:54.098] LOG -- rfcomm.c.1607: Received SABM #2
[834.098500] [00:13:54.098] EVT <= 79 02 44 00
[834.098665] [00:13:54.098] LOG -- rfcomm.c.2029: Sending UA #2
[834.098884] [00:13:54.098] ACL => 80 00 08 00 04 00 41 00 0B 73 01 92
[834.099135] [00:13:54.099] LOG -- rfcomm.c.1782: rfcomm_channel_ready_for_incoming_dlc_setup state var 00000007
[834.099519] [00:13:54.099] LOG -- rfcomm.c.2034: Incomping setup done, requesting send MSC CMD and send Credits
[834.099904] [00:13:54.099] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.100265] [00:13:54.100] EVT <= 6E 00
[834.100410] [00:13:54.100] EVT <= 79 02 44 00
[834.100576] [00:13:54.100] LOG -- rfcomm.c.1942: Sending MSC CMD for #2
[834.100825] [00:13:54.100] ACL => 80 00 0C 00 08 00 41 00 01 EF 09 E3 05 0B 8D AA
[834.101117] [00:13:54.101] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.101457] [00:13:54.101] EVT <= 6E 00
[834.101601] [00:13:54.101] EVT <= 79 02 44 00
[834.101768] [00:13:54.101] LOG -- rfcomm.c.2123: Providing credits for #2
[834.102023] [00:13:54.102] ACL => 80 00 09 00 05 00 41 00 09 FF 01 0A 5C
[834.102285] [00:13:54.102] LOG -- rfcomm.c.1774: rfcomm_channel_ready_for_open state 8, flags needed 0010, current 00014007, rf credits 5
[834.102757] [00:13:54.102] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.103096] [00:13:54.103] EVT <= 6E 00
[834.103240] [00:13:54.103] EVT <= 79 02 44 00
[834.103404] [00:13:54.103] EVT <= 89 02 02 00
[834.103570] [00:13:54.103] EVT <= 79 02 44 00
[834.105217] [00:13:54.105] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.105575] [00:13:54.105] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 by3:54.115] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.115377] [00:13:54.115] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 13 bytes
[834.116384] [00:13:54.116] ACL <= 80 20 0D 00 09 00 44 00 03 EF 08 00 E1 05 0B 8D 70
[834.116682] [00:13:54.116] LOG -- rfcomm.c.1667: Received MSC RSP for #2
[834.116939] [00:13:54.116] LOG -- rfcomm.c.1774: rfcomm_channel_ready_for_open state 8, flags needed 0010, current 0001c01f, rf credits 5
[834.117407] [00:13:54.117] LOG -- rfcomm.c.1410: opened
[834.117608] [00:13:54.117] LOG -- rfcomm.c.267: RFCOMM_EVENT_CHANNEL_OPENED status 0x0 addr E0:9D:FA:5C:9D:AE handle 0x80 channel #1 cid 0x02 mtu 666
[834.118115] [00:13:54.118] EVT <= 80 10 00 AE 9D 5C FA 9D E0 80 00 01 02 00 9A 02 01 00
[834.118420] [00:13:54.118] EVT <= 79 02 44 00
[834.118583] [00:13:54.118] EVT <= 89 02 02 00
[834.118749] [00:13:54.118] EVT <= 79 02 44 00
[834.118914] [00:13:54.118] EVT <= 89 02 02 00
[834.119078] [00:13:54.119] EVT <= 88 0A 02 00 00 03 03 00 D1 D3 7F 3F
[834.119328] [00:13:54.119] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.119687] [00:13:54.119] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.120045] [00:13:54.120] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.120398] [00:13:54.120] EVT <= 13 05 01 80 00 01 00
[834.120600] [00:13:54.120] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.149375] [00:13:54.149] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.149733] [00:13:54.149] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 21 bytes
[834.150091] [00:13:54.150] ACL <= 80 20 15 00 11 00 44 00 0B FF 16 00 00 41 54 2B 42 52 53 46 3D 33 31 0D 86
[834.150470] [00:13:54.150] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 5
[834.150827] [00:13:54.150] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+BRSF=31\r'
[834.151082] [00:13:54.151] LOG -- hfp.c.1352: command string 'AT+BRSF=', handsfree 0 -> cmd id 5
[834.151415] [00:13:54.151] LOG -- hfp.c.1641: Parsed supported feature 31

[834.151682] [00:13:54.151] LOG -- hfp.c.241: HFP_TX
+BRSF:16365

OK

[834.151939] [00:13:54.151] ACL => 80 00 1E 00 1A 00 41 00 09 EF 2A 00 0D 0A 2B 42 52 53 46 3A 31 36 33 36 35 0D 0A 0D 0A 4F 4B 0D 0A 40
[834.152414] [00:13:54.152] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.152767] [00:13:54.152] EVT <= 6E 00
[834.152912] [00:13:54.152] EVT <= 79 02 44 00
[834.153076] [00:13:54.153] EVT <= 89 02 02 00
[834.153242] [00:13:54.153] EVT <= 79 02 44 00
[834.158968] [00:13:54.158] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.159327] [00:13:54.159] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.159681] [00:13:54.159] EVT <= 13 05 01 80 00 01 00
[834.159882] [00:13:54.159] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.188035] [00:13:54.188] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.188394] [00:13:54.188] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.188752] [00:13:54.188] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 43 49 4E 44 3D 3F 0D 86
[834.189122] [00:13:54.189] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 4
[834.189479] [00:13:54.189] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CIND=?\r'
[834.189729] [00:13:54.189] LOG -- hfp.c.1352: command string 'AT+CIND=?', handsfree 0 -> cmd id 8
[834.190081] [00:13:54.190] ACL => 80 00 92 00 8E 00 41 00 09 EF 12 01 0D 0A 2B 43 49 4E 44 3A 28 22 73 65 72 76 69 63 65 22 2C 28 30 2C 31 29 29 2C 28 22 63 61 6C 6C 22 2C 28 30 2C 31 29 29 2C 28 22 63 61 6C 6C 73 65 74 75 70 22 2C 28 30 2C 33 29 29 2C 28 22 62 61 74 74 63 68 67 22 2C 28 30 2C 35 29 29 2C 28 22 73 69 67 6E 61 6C 22 2C 28 30 2C 35 29 29 2C 28 22 72 6F 61 6D 22 2C 28 30 2C 31 29 29 2C 28 22 63 61 6C 6C 68 65 6C 64 22 2C 28 30 2C 32 29 29 0D 0A 0D 0A 4F 4B 0D 0A 40
[834.199956] [00:13:54.199] LOG -- hfp_ag.c.771: HFP_CMD_RETRIEVE_AG_INDICATORS next segment 15, num_segments 15
[834.200352] [00:13:54.200] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.200704] [00:13:54.200] EVT <= 6E 00
[834.200850] [00:13:54.200] EVT <= 79 02 44 00
[834.201013] [00:13:54.201] EVT <= 89 02 02 00
[834.201179] [00:13:54.201] EVT <= 79 02 44 00
[834.211468] [00:13:54.211] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.211826] [00:13:54.211] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.212180] [00:13:54.212] EVT <= 13 05 01 80 00 01 00
[834.212382] [00:13:54.212] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.240446] [00:13:54.240] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.240804] [00:13:54.240] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 19 bytes
[834.241162] [00:13:54.241] ACL <= 80 20 13 00 0F 00 44 00 0B FF 12 00 00 41 54 2B 43 49 4E 44 3F 0D 86
[834.241521] [00:13:54.241] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 3
[834.241878] [00:13:54.241] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CIND?\r'
[834.242125] [00:13:54.242] LOG -- hfp.c.1352: command string 'AT+CIND?', handsfree 0 -> cmd id 9
[834.242464] [00:13:54.242] LOG -- hfp.c.241: HFP_TX
+CIND:1,0,0,3,5,0,0

OK

[834.242748] [00:13:54.242] ACL => 80 00 26 00 22 00 41 00 09 EF 3A 00 0D 0A 2B 43 49 4E 44 3A 31 2C 30 2C 30 2C 33 2C 35 2C 30 2C 30 0D 0A 0D 0A 4F 4B 0D 0A 40
[834.243303] [00:13:54.243] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.243656] [00:13:54.243] EVT <= 6E 00
[834.243801] [00:13:54.243] EVT <= 79 02 44 00
[834.243965] [00:13:54.243] EVT <= 89 02 02 00
[834.244131] [00:13:54.244] EVT <= 79 02 44 00
[834.251469] [00:13:54.251] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.251828] [00:13:54.251] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.252182] [00:13:54.252] EVT <= 13 05 01 80 00 01 00
[834.252384] [00:13:54.252] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.280862] [00:13:54.280] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.281220] [00:13:54.281] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 26 bytes
[834.281579] [00:13:54.281] ACL <= 80 20 1A 00 16 00 44 00 0B FF 20 00 00 41 54 2B 43 4D 45 52 3D 33 2C 30 2C 30 2C 31 0D 86
[834.282008] [00:13:54.282] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 2
[834.282366] [00:13:54.282] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CMER=3,0,0,1\r'
[834.282636] [00:13:54.282] LOG -- hfp.c.1352: command string 'AT+CMER=', handsfree 0 -> cmd id 10
[834.282973] [00:13:54.282] LOG -- hfp.c.1662: Parsed Enable indicators: 1

[834.283238] [00:13:54.283] LOG -- hfp.c.241: HFP_TX
OK

[834.283444] [00:13:54.283] ACL => 80 00 0F 00 0B 00 41 00 09 EF 0C 00 0D 0A 4F 4B 0D 0A 40
[834.283767] [00:13:54.283] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.284121] [00:13:54.284] EVT <= 6E 00
[834.284266] [00:13:54.284] EVT <= 79 02 44 00
[834.284430] [00:13:54.284] EVT <= 89 02 02 00
[834.284595] [00:13:54.284] EVT <= 79 02 44 00
[834.284766] [00:13:54.284] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.285125] [00:13:54.285] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[834.285483] [00:13:54.285] ACL <= 80 20 0A 00 06 00 44 00 0B FF 00 00 03 86
[834.285752] [00:13:54.285] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 3, now 4
[834.286111] [00:13:54.286] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.288970] [00:13:54.288] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.289328] [00:13:54.289] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.289682] [00:13:54.289] EVT <= 13 05 01 80 00 01 00
[834.289884] [00:13:54.289] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.318040] [00:13:54.318] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.318399] [00:13:54.318] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.318758] [00:13:54.318] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 43 48 4C 44 3D 3F 0D 86
[834.319128] [00:13:54.319] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 4
[834.319484] [00:13:54.319] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CHLD=?\r'
[834.319735] [00:13:54.319] LOG -- hfp.c.1352: command string 'AT+CHLD=?', handsfree 0 -> cmd id 12
[834.320080] [00:13:54.320] LOG -- hfp.c.241: HFP_TX
+CHLD:(1,1x,2,2x,3)

OK

[834.320363] [00:13:54.320] ACL => 80 00 26 00 22 00 41 00 09 EF 3A 00 0D 0A 2B 43 48 4C 44 3A 28 31 2C 31 78 2C 32 2C 32 78 2C 33 29 0D 0A 0D 0A 4F 4B 0D 0A 40
[834.320912] Service level connection established to E0:9D:FA:5C:9D:AE.
[834.321154] Supported codecs: CVSD
[834.321282] [00:13:54.321] LOG -- hfp_ag_demo.c.1054: Establish Audio connection E0:9D:FA:5C:9D:AE
[834.321618] Establish Audio connection E0:9D:FA:5C:9D:AE...
[834.321831] [00:13:54.321] LOG -- hfp_ag.c.2709: hfp_ag_establish_audio_connection - no codec negotiation feature, using CVSD
[834.322260] [00:13:54.322] LOG -- hfp.c.2082: hfp_init_link_settings: 4
[834.322511] [00:13:54.322] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.322848] earphone connect success
[834.322984] [00:13:54.322] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.323338] [00:13:54.323] EVT <= 6E 00
[834.323483] [00:13:54.323] EVT <= 79 02 44 00
[834.323647] [00:13:54.323] EVT <= 89 02 02 00
[834.323816] [00:13:54.323] LOG -- hfp.c.1903: hfp_setup_synchronous_connection using setting nr 4
[834.324151] [00:13:54.324] CMD => 28 04 11 80 00 40 1F 00 00 40 1F 00 00 0A 00 60 00 01 80 03
[834.324484] [00:13:54.324] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.324823] [00:13:54.324] EVT <= 6E 00
[834.324968] [00:13:54.324] EVT <= 79 02 44 00
[834.325132] [00:13:54.325] EVT <= 89 02 02 00
[834.325297] [00:13:54.325] EVT <= 79 02 44 00
[834.327438] [00:13:54.327] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.327796] [00:13:54.327] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.328150] [00:13:54.328] EVT <= 0F 04 00 05 28 04
[834.328343] [00:13:54.328] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.330352] [00:13:54.330] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.330709] [00:13:54.330] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.331065] [00:13:54.331] EVT <= 13 05 01 80 00 01 00
[834.331267] [00:13:54.331] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.358873] [00:13:54.358] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.359231] [00:13:54.359] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 17 bytes
[834.359589] [00:13:54.359] EVT <= 2C 11 00 80 01 AE 9D 5C FA 9D E0 02 0C 02 3C 00 3C 00 02
[834.359908] [00:13:54.359] LOG -- hci.c.3527: Synchronous Connection Complete for 00000000 (status=0) E0:9D:FA:5C:9D:AE
[834.360319] [00:13:54.360] LOG -- hci.c.328: create_connection_for_addr E0:9D:FA:5C:9D:AE, type fc
[834.360662] [00:13:54.360] LOG -- hfp.c.908: eSCO Connection established.

[834.360937] [00:13:54.360] LOG -- hfp.c.918: sco_handle 0x180, address E0:9D:FA:5C:9D:AE, transmission_interval 12 slots, retransmission_interval 2 slots, rx_packet_length 60 bytes, tx_packet_length 60 bytes, air_mode 0x 2 (0x02 == CVSD)

[834.361748] Audio connection established with SCO handle 0x0180.
[834.361971] Using CVSD codec.
[834.362078] SCO Demo: Init CVSD
[834.362202] [00:13:54.362] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.362561] [00:13:54.362] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.362918] [00:13:54.362] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.364314] [00:13:54.364] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 43 43 57 41 3D 31 0D 86
[834.364683] [00:13:54.364] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 3
[834.365040] [00:13:54.365] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CCWA=1\r'
[834.365291] [00:13:54.365] LOG -- hfp.c.1352: command string 'AT+CCWA=', handsfree 0 -> cmd id 15
[834.365628] [00:13:54.365] LOG -- hfp_ag.c.2509: hfp: call waiting notification set, now: 1
[834.365946] [00:13:54.365] LOG -- hfp.c.241: HFP_TX
OK

[834.366153] [00:13:54.366] ACL => 80 00 0F 00 0B 00 41 00 09 EF 0C 00 0D 0A 4F 4B 0D 0A 40
[834.366476] [00:13:54.366] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.366830] [00:13:54.366] EVT <= 6E 00
[834.366975] [00:13:54.366] EVT <= 79 02 44 00
[834.367143] [00:13:54.367] ACL => 80 00 09 00 05 00 41 00 09 FF 01 0A 5C
[834.367405] [00:13:54.367] LOG -- hfp_ag.c.2278: hfp_ag_run_for_context: request can send for 0x02
[834.367744] [00:13:54.367] EVT <= 6E 00
[834.367889] [00:13:54.367] EVT <= 79 02 44 00
[834.368054] [00:13:54.368] EVT <= 89 02 02 00
[834.368220] [00:13:54.368] EVT <= 79 02 44 00
[834.377725] [00:13:54.377] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.378084] [00:13:54.378] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.378438] [00:13:54.378] EVT <= 13 05 01 80 00 01 00
[834.378640] [00:13:54.378] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.380639] [00:13:54.380] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.380997] [00:13:54.380] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.381351] [00:13:54.381] EVT <= 13 05 01 80 00 01 00
[834.381552] [00:13:54.381] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.409610] [00:13:54.409] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.409968] [00:13:54.409] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.410327] [00:13:54.410] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 43 4C 49 50 3D 31 0D 86
[834.410696] [00:13:54.410] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 2
[834.411055] [00:13:54.411] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CLIP=1\r'
[834.411306] [00:13:54.411] LOG -- hfp.c.1352: command string 'AT+CLIP=', handsfree 0 -> cmd id 13
[834.411642] [00:13:54.411] LOG -- hfp_ag.c.2504: hfp: clip set, now: 1
[834.411889] [00:13:54.411] LOG -- hfp.c.241: HFP_TX
OK

[834.412096] [00:13:54.412] ACL => 80 00 0F 00 0B 00 41 00 09 EF 0C 00 0D 0A 4F 4B 0D 0A 40
[834.412418] [00:13:54.412] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.412773] [00:13:54.412] EVT <= 6E 00
[834.412918] [00:13:54.412] EVT <= 79 02 44 00
[834.413083] [00:13:54.413] EVT <= 89 02 02 00
[834.413248] [00:13:54.413] EVT <= 79 02 44 00
[834.413420] [00:13:54.413] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.413779] [00:13:54.413] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 10 bytes
[834.414137] [00:13:54.414] ACL <= 80 20 0A 00 06 00 44 00 0B FF 00 00 03 86
[834.414406] [00:13:54.414] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 3, now 4
[834.414765] [00:13:54.414] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.422725] [00:13:54.422] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.423083] [00:13:54.423] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.423436] [00:13:54.423] EVT <= 13 05 01 80 00 01 00
[834.423639] [00:13:54.423] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.454130] [00:13:54.454] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.454488] [00:13:54.454] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 18 bytes
[834.454846] [00:13:54.454] ACL <= 80 20 12 00 0E 00 44 00 0B FF 10 00 00 41 54 2B 43 4C 43 43 0D 86
[834.455195] [00:13:54.455] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 4
[834.455552] [00:13:54.455] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+CLCC\r'
[834.455796] [00:13:54.455] LOG -- hfp.c.1352: command string 'AT+CLCC', handsfree 0 -> cmd id 45
[834.456130] [00:13:54.456] LOG -- hfp.c.241: HFP_TX
OK

[834.456337] [00:13:54.456] ACL => 80 00 0F 00 0B 00 41 00 09 EF 0C 00 0D 0A 4F 4B 0D 0A 40
[834.456659] [00:13:54.456] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.457012] [00:13:54.457] EVT <= 6E 00
[834.457158] [00:13:54.457] EVT <= 79 02 44 00
[834.457322] [00:13:54.457] EVT <= 89 02 02 00
[834.457488] [00:13:54.457] EVT <= 79 02 44 00
[834.467727] [00:13:54.467] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.468085] [00:13:54.468] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.468439] [00:13:54.468] EVT <= 13 05 01 80 00 01 00
[834.468640] [00:13:54.468] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.499314] [00:13:54.499] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.499672] [00:13:54.499] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.500030] [00:13:54.500] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 56 47 53 3D 31 34 0D 86
[834.500407] [00:13:54.500] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 3
[834.500764] [00:13:54.500] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+VGS=14\r'
[834.501014] [00:13:54.501] LOG -- hfp.c.1352: command string 'AT+VGS=', handsfree 0 -> cmd id 43
[834.501348] [00:13:54.501] LOG -- hfp.c.1619: hfp parse HFP_CMD_SET_SPEAKER_GAIN 14

[834.501644] [00:13:54.501] LOG -- hfp_ag.c.2515: HF speaker gain = 14
[834.501883] Speaker volume: gain 14
[834.502014] [00:13:54.502] LOG -- hfp.c.241: HFP_TX
OK

[834.502222] [00:13:54.502] ACL => 80 00 0F 00 0B 00 41 00 09 EF 0C 00 0D 0A 4F 4B 0D 0A 40
[834.502544] [00:13:54.502] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.502898] [00:13:54.502] EVT <= 6E 00
[834.503043] [00:13:54.503] EVT <= 79 02 44 00
[834.503208] [00:13:54.503] EVT <= 89 02 02 00
[834.503373] [00:13:54.503] EVT <= 79 02 44 00
[834.512729] [00:13:54.512] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 2 bytes
[834.513088] [00:13:54.513] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 5 bytes
[834.513442] [00:13:54.513] EVT <= 13 05 01 80 00 01 00
[834.513644] [00:13:54.513] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 1 bytes
[834.544615] [00:13:54.544] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 4 bytes
[834.544973] [00:13:54.544] LOG -- hci_transport_h4.c.190: hci_transport_h4_trigger_next_read: 20 bytes
[834.545332] [00:13:54.545] ACL <= 80 20 14 00 10 00 44 00 0B FF 14 00 00 41 54 2B 56 47 4D 3D 31 34 0D 86
[834.545701] [00:13:54.545] LOG -- rfcomm.c.1447: RFCOMM data UIH_PF, new credits channel 0x02: 0, now 2
[834.546060] [00:13:54.546] LOG -- hfp.c.2109: HFP_AG_RX: 'AT+VGM=14\r'
[834.546310] [00:13:54.546] LOG -- hfp.c.1352: command string 'AT+VGM=', handsfree 0 -> cmd id 42
[834.546644] [00:13:54.546] LOG -- hfp.c.1614: hfp parse HFP_CMD_SET_MICROPHONE_GAIN 14

[834.546949] [00:13:54.546] LOG -- hfp_ag.c.2521: HF microphone gain = 14
[834.547199] Microphone volume: gain 14
[834.547340] [00:13:54.547] LOG -- hfp.c.241: HFP_TX
OK

my problem is that i found data from pcm interface of esp32 is i2s format by measuring , but the pcm interface of my host chip can only support pcm protocol(mode A , mode B), so i want know whether esp32 can set pcm signal to pcm format instead of i2s format

@mantriyogesh
Copy link
Collaborator

Hello @zhou88mang ,

Thank you for the logs.

Can you please attach the sdkconfig at your side?

@zhou88mang
Copy link
Author

zhou88mang commented Jun 6, 2023

Hello @zhou88mang ,

Thank you for the logs.

Can you please attach the sdkconfig at your side?

this is sdkconfig :
sdkconfig.txt

and my code related pcm configure is as same as hfp-ag demo of esp-idf, as follow :

#include "driver/gpio.h"
#include "soc/gpio_reg.h"
#include "soc/gpio_sig_map.h"
#include "gpio_pcm_config.h"
#include "esp_rom_gpio.h"
#include "gpio_pcm_config.h"
#define GPIO_OUTPUT_PCM_FSYNC      (19)
#define GPIO_OUTPUT_PCM_CLK_OUT    (23)
#define GPIO_OUTPUT_PCM_DOUT       (18)
#define GPIO_INPUT_PCM_DIN         (22)

#define GPIO_OUTPUT_PCM_PIN_SEL  ((1ULL<<GPIO_OUTPUT_PCM_FSYNC) | (1ULL<<GPIO_OUTPUT_PCM_CLK_OUT) | (1ULL<<GPIO_OUTPUT_PCM_DOUT))

#define GPIO_INPUT_PCM_PIN_SEL (1ULL<<GPIO_INPUT_PCM_DIN)

void app_gpio_pcm_io_cfg(void)
{
    gpio_config_t io_conf;
    /// configure the PCM output pins
    //disable interrupt
    io_conf.intr_type = GPIO_INTR_DISABLE;
    //set as output mode
    io_conf.mode = GPIO_MODE_OUTPUT;
    //bit mask of the pins that you want to set,e.g.GPIO18/19
    io_conf.pin_bit_mask = GPIO_OUTPUT_PCM_PIN_SEL;
    //disable pull-down mode
    io_conf.pull_down_en = 0;
    //disable pull-up mode
    io_conf.pull_up_en = 0;
    //configure GPIO with the given settings
    gpio_config(&io_conf);

    // configure the PCM input pin
    //interrupt of rising edge
    io_conf.intr_type = GPIO_INTR_DISABLE;
    //bit mask of the pins, use GPIO4/5 here
    io_conf.pin_bit_mask = GPIO_INPUT_PCM_PIN_SEL;
    //set as input mode
    io_conf.mode = GPIO_MODE_INPUT;
    //enable pull-up mode
    io_conf.pull_up_en = 0;
    io_conf.pull_down_en = 0;
    //configure GPIO with the given settings
    gpio_config(&io_conf);

    // matrix out | in the internal PCM signals to the GPIOs
    esp_rom_gpio_connect_out_signal(GPIO_OUTPUT_PCM_FSYNC, PCMFSYNC_OUT_IDX, false, false);
    esp_rom_gpio_connect_out_signal(GPIO_OUTPUT_PCM_CLK_OUT, PCMCLK_OUT_IDX, false, false);
    esp_rom_gpio_connect_out_signal(GPIO_OUTPUT_PCM_DOUT, PCMDOUT_IDX, false, false);
    esp_rom_gpio_connect_in_signal(GPIO_INPUT_PCM_DIN, PCMDIN_IDX, false);
}

@mantriyogesh
Copy link
Collaborator

Hello @zhou88mang ,

espressif/esp-idf#11589 was created with idf. As i can see, you had closed this issue, which internally closes our internal idf ticket.

In ESP-Hosted, We use controller and use HCI packets.
Bluetooth over UART, which is seamless mode for ESP-Hosted (ESP-Hosted code is not involved), where UART data (i.e. BT control+data) directly sent to ESP chipset controller using HCI interface.

ESP-IDF example hfp-ag demo is over Bluedriod (stack runs without HCI interface, which is fundamentally difference in ESP-Hosted and HFP_AG example.

As HCI is directly consumed at controller, if something is not working as expected, it must be either at the stack configuration or controller consuming hci.

Is the issue resolved? If not, please reopen espressif/esp-idf#11589.

@mantriyogesh
Copy link
Collaborator

I hope you have not changed the code apart from enabling uart and using max SCO connections.

Crux here is that ESP-Hosted uses HCI interface (stack over host, controller over esp) whereas, ESP-IDF example hfp_ag uses Bluedroid directly in conjunction with controller locally present.

@zhou88mang
Copy link
Author

Thank for your reply !
I'll take a closer look at the protocol stack I'm using

@zhou88mang
Copy link
Author

I hope you have not changed the code apart from enabling uart and using max SCO connections.

Crux here is that ESP-Hosted uses HCI interface (stack over host, controller over esp) whereas, ESP-IDF example hfp_ag uses Bluedroid directly in conjunction with controller locally present.

but, if i just use HFP-AG demo , i did not found any ways to configure pcm format , it just provided the function "app_gpio_pcm_io_cfg", I don't know how it generated these pcm signals inside, I suspect this was done inside the controller,
maybe it has been done in function "esp_bt_controller_init" , i dont know.

@mantriyogesh
Copy link
Collaborator

The Bluetooth team was looking in this matter till you had closed espressif/esp-idf#11589

@mantriyogesh
Copy link
Collaborator

any progress ahead?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants