espressif / esp-hosted

Hosted Solution (Linux/MCU) with ESP32 (Wi-Fi + BT + BLE)
Other
624 stars 144 forks source link

`iw espsta0 scan` doesn't return anything #411

Open naguirre opened 1 week ago

naguirre commented 1 week ago

Hello. I'm trying to set up ESP Hosted NG. My hardware is a custom board I developed based on an ESP32-C3-Mini and an Allwinner f1c200s. I built the latest version of esp-hosted-ng (b1422af) firmware, and the Linux kernel version on the F1c200s is 6.9.2. I followed the porting guide and started by validating the SPI communication between ESP32-C3 and F1c200s with spidev. So far so good, I was able to validate the communication at 30Mhz between the two devices.

I noticed, though, that when the SPI slave uses MODE2, the data received on F1c200s are invalid. Whether I'm using MODE0 or MODE1, the data are correct. So, for the next tests, I'm using MODE1 for esp32 and MODE2 for the host.

With spidev I didn't notice any issue with the communication after those changes. I was able to read and write data from the ESP32-C3 to the F1c200s and vice versa. I used the ESP32 SPI slave example and developed a small app for the F1c200s.

I then flashed the ESP Hosted NG firmware, and built esp32_spi.

The reset GPIO line is fine, and I can see the ESP32 resetting when the line is asserted even if I had to raise the pin to 1 and increase the reset time a bit instead of just getting it back to input.

diff --git a/esp_hosted_ng/host/main.c b/esp_hosted_ng/host/main.c
index 258d2bd..30cf84b 100644
--- a/esp_hosted_ng/host/main.c
+++ b/esp_hosted_ng/host/main.c
@@ -961,7 +961,9 @@ static void esp_reset(void)

                        /* HOST's resetpin set to LOW */
                        gpio_set_value(resetpin, 0);
-                       udelay(200);
+                       mdelay(200);
+                       gpio_set_value(resetpin, 1);
+

                        /* HOST's resetpin set to INPUT */
                        gpio_direction_input(resetpin);

The Handhsake and data-ready pins look good as well, and I can see the interrupts being triggered on the F1c200s side.

The main problem is that the command iw espsta0 scan does not work. It hangs, and I never get a result.

The logs from ESP32 :

ESP-ROM:esp32c3-api1-20210207
Build:Feb  7 2021
rst:0x1 (POWERON),boot:0xa (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x170c
load:0x403cc710,len:0x968
load:0x403ce710,len:0x2f9c
entry 0x403cc710
I (30) boot: ESP-IDF v5.1.3-dirty 2nd stage bootloader
I (30) boot: compile time Jun 22 2024 15:20:15
I (30) boot: chip revision: v0.4
I (34) boot.esp32c3: SPI Speed      : 80MHz
I (39) boot.esp32c3: SPI Mode       : DIO
I (43) boot.esp32c3: SPI Flash Size : 4MB
I (48) boot: Enabling RNG early entropy source...
I (53) boot: Partition Table:
I (57) boot: ## Label            Usage          Type ST Offset   Length
I (64) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (72) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (79) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (87) boot:  3 factory          factory app      00 00 00010000 00100000
I (94) boot:  4 ota_0            OTA app          00 10 00110000 00100000
I (102) boot:  5 ota_1            OTA app          00 11 00210000 00100000
I (109) boot: End of partition table
I (113) boot: Defaulting to factory image
I (118) esp_image: segment 0: paddr=00010020 vaddr=3c090020 size=1faf8h (129784) map
I (148) esp_image: segment 1: paddr=0002fb20 vaddr=3fc94600 size=004f8h (  1272) load
I (148) esp_image: segment 2: paddr=00030020 vaddr=42000020 size=8be94h (573076) map
I (246) esp_image: segment 3: paddr=000bbebc vaddr=3fc94af8 size=02de4h ( 11748) load
I (249) esp_image: segment 4: paddr=000beca8 vaddr=40380000 size=145d8h ( 83416) load
I (274) boot: Loaded app from partition at offset 0x10000
I (275) boot: Disabling RNG early entropy source...
I (286) cpu_start: Unicore app
I (286) cpu_start: Pro cpu up.
I (295) cpu_start: Pro cpu start user code
I (295) cpu_start: cpu freq: 160000000 Hz
I (295) cpu_start: Application information:
I (298) cpu_start: Project name:     network_adapter
I (304) cpu_start: App version:      release/ng-v1.0.2-262-gb1422af-
I (311) cpu_start: Compile time:     Jun 22 2024 15:20:09
I (317) cpu_start: ELF file SHA256:  6dfd7facb45702bb...
I (323) cpu_start: ESP-IDF:          v5.1.3-dirty
I (328) cpu_start: Min chip rev:     v0.3
I (333) cpu_start: Max chip rev:     v1.99
I (338) cpu_start: Chip rev:         v0.4
I (342) heap_init: Initializing. RAM available for dynamic allocation:
I (350) heap_init: At 3FC9B950 len 000246B0 (145 KiB): DRAM
I (356) heap_init: At 3FCC0000 len 0001C710 (113 KiB): DRAM/RETENTION
I (363) heap_init: At 3FCDC710 len 00002950 (10 KiB): DRAM/RETENTION/STACK
I (370) heap_init: At 50000010 len 00001FD8 (7 KiB): RTCRAM
I (378) spi_flash: detected chip: generic
I (381) spi_flash: flash io: dio
I (386) sleep: Configure to isolate all GPIO pins in sleep state
I (392) sleep: Enable automatic switching of GPIO sleep configuration
I (399) coexist: coex firmware version: 77cd7f8
I (405) coexist: coexist rom version 9387209
I (410) app_start: Starting scheduler on CPU0
I (414) main_task: Started on CPU0
I (418) main_task: Calling app_main()
I (422) stats: *********************************************************************
I (430) stats:                 ESP-Hosted Firmware version :: 1.0.3
I (439) stats:                 Transport used :: SPI only
I (447) stats: *********************************************************************
I (456) FW_MAIN: Supported features are:
I (460) FW_MAIN: - WLAN over SPI
I (464) FW_BT: - BT/BLE
I (467) FW_BT:    - HCI Over SPI
I (471) FW_BT:    - BLE only
I (475) FW_MAIN: Capabilities: 0xe8
pp rom version: 9387209
net80211 rom version: 9387209
I (485) wifi:wifi driver task: 3fca1df0, prio:23, stack:6656, core=0
I (491) wifi:wifi firmware version: e3cf69a
I (494) wifi:wifi certification version: v7.0
I (498) wifi:config NVS flash: disabled
I (502) wifi:config nano formating: disabled
I (506) wifi:Init data frame dynamic rx buffer num: 40
I (511) wifi:Init static rx mgmt buffer num: 5
I (515) wifi:Init management short buffer num: 32
I (519) wifi:Init dynamic tx buffer num: 40
I (523) wifi:Init static tx FG buffer num: 2
I (527) wifi:Init static rx buffer size: 1600
I (531) wifi:Init static rx buffer num: 20
I (535) wifi:Init dynamic rx buffer num: 40
I (539) wifi_init: rx ba win: 32
I (543) wifi_init: tcpip mbox: 32
I (547) wifi_init: udp mbox: 6
I (550) wifi_init: tcp mbox: 6
I (554) wifi_init: tcp tx win: 5760
I (558) wifi_init: tcp rx win: 5760
I (562) wifi_init: tcp mss: 1440
I (566) wifi_init: WiFi IRAM OP enabled
I (571) wifi_init: WiFi RX IRAM OP enabled
I (576) BLE_INIT: BT controller compile version [9359a4d]
I (582) BLE_INIT: Bluetooth MAC: f0:f5:bd:f6:80:ca

I (588) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21
I (626) FW_MAIN: ESP Bluetooth MAC addr: f0-f5-bd-f6-80-ca

I (627) FW_SPI: Using SPI interface
I (627) gpio: GPIO[3]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (635) gpio: GPIO[4]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
SPI Slave mode : 1
I (1647) FW_MAIN: Initial set up done
I (1647) main_task: Returned from app_main()
I (1669) FW_SPI: 0x3fcb8860   03 00 00 00 1a 00 0c 00  52 01 00 00 01 00 13 00  |........R.......|
I (1669) FW_SPI: 0x3fcb8870   12 00 00 00 03 01 05 02  01 01 00 01 e8 01 07 01  |................|
I (1760) FW_SPI: 0x3fc9d8dc   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1760) FW_SPI: 0x3fc9d8ec   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1769) SDIO Rx: 0x3fc9df2c   01 00 00 00 00 00 00 00                           |........|
I (1777) Rx Cmd: 0x3fc9df2c   01 00 00 00 00 00 00 00                           |........|
I (1786) FW_MAIN: INIT Interface command

I (1792) wifi:mode : sta (f0:f5:bd:f6:80:c8)
I (1795) wifi:enable tsf
I (1797) FW_CMD: Wifi Sta mode set

I (1816) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1816) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1833) FW_SPI: 0x3fc9e96c   00 00 02 00 08 00 0c 00  19 00 00 00 01 02 00 00  |................|
I (1834) FW_SPI: 0x3fc9e97c   00 00 00 00 64 e9 c9 3f  54 00 00 00 88 e9 c9 3f  |....d..?T......?|
I (1850) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1853) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1864) SDIO Rx: 0x3fcb8eb0   03 00 00 00 00 00 00 00                           |........|
I (1872) Rx Cmd: 0x3fcb8eb0   03 00 00 00 00 00 00 00                           |........|
I (1881) FW_MAIN: Get MAC command

I (1885) FW_CMD: 0x3fcb74e0   f0 f5 bd f6 80 c8                                 |......|
I (1912) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1913) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1930) FW_SPI: 0x3fc9df34   00 00 02 00 10 00 0c 00  09 05 00 00 03 02 06 00  |................|
I (1931) FW_SPI: 0x3fc9df44   00 00 00 00 f0 f5 bd f6  80 c8 00 00 54 00 00 00  |............T...|
I (1955) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1955) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1964) SDIO Rx: 0x3fcb9ff8   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (1973) Rx Cmd: 0x3fcb9ff8   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (1982) FW_MAIN: Tx power command

I (1986) MAC Filter: 0x3fc9843c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1996) MAC Filter: 0x3fc9844c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2006) MAC Filter: 0x3fc9845c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2016) MAC Filter: 0x3fc9846c   00                                                |.|
I (2043) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2044) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2061) FW_SPI: 0x3fc9df38   00 00 02 00 0c 00 0c 00  7b 00 00 00 0f 02 00 00  |........{.......|
I (2062) FW_SPI: 0x3fc9df48   00 00 00 00 50 00 00 00  54 00 00 00 54 df c9 3f  |....P...T...T..?|
I (2339) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2339) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2348) SDIO Rx: 0x3fcb8eb1   03 0c 00                                          |...|
I (2356) H->S BT: 0x3fcb8eb1   03 0c 00                                          |...|
I (2383) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2383) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2400) FW_SPI: 0x3fc9df30   02 00 00 00 07 00 0c 00  3f 00 00 00 04 0e 04 05  |........?.......|
I (2401) FW_SPI: 0x3fc9df40   03 0c 00 00 0f 02 00 00  00 00 00 00 28 df c9 3f  |............(..?|
I (2418) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2420) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2431) SDIO Rx: 0x3fcb9ff9   03 10 00                                          |...|
I (2439) H->S BT: 0x3fcb9ff9   03 10 00                                          |...|
I (2466) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2466) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2482) FW_SPI: 0x3fc9dfac   02 00 00 00 0f 00 0c 00  b3 00 00 00 04 0e 0c 05  |................|
I (2484) FW_SPI: 0x3fc9dfbc   03 10 00 00 00 00 00 60  00 00 00 3f 95 05 00 00  |.......`...?....|
I (2496) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2503) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2514) SDIO Rx: 0x3fcb8eb1   01 10 00                                          |...|
I (2521) H->S BT: 0x3fcb8eb1   01 10 00                                          |...|
I (2549) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2549) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2566) FW_SPI: 0x3fc9dfac   02 00 00 00 0f 00 0c 00  76 01 00 00 04 0e 0c 05  |........v.......|
I (2567) FW_SPI: 0x3fc9dfbc   01 10 00 09 16 00 09 e5  02 16 00 3f 95 05 00 00  |...........?....|
I (2583) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2586) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2597) SDIO Rx: 0x3fcb9ff9   09 10 00                                          |...|
I (2604) H->S BT: 0x3fcb9ff9   09 10 00                                          |...|
I (2632) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2632) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2649) FW_SPI: 0x3fc9dfac   02 00 00 00 0d 00 0c 00  37 05 00 00 04 0e 0a 05  |........7.......|
I (2650) FW_SPI: 0x3fc9dfbc   09 10 00 ca 80 f6 bd f5  f0 df c9 3f 95 05 00 00  |...........?....|
I (2667) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2669) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2680) SDIO Rx: 0x3fcb8eb1   02 10 00                                          |...|
I (2687) H->S BT: 0x3fcb8eb1   02 10 00                                          |...|
I (2715) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2715) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80729) FW_SPI: 0x3fc9dff8   02 00 00 00 47 00 0c 00  ea 0c 00 00 04 0e 44 05  |....G.........D.|
I (80730) FW_SPI: 0x3fc9e008   02 10 00 20 00 80 00 00  c0 00 00 00 00 e4 00 00  |... ............|
I (80739) SDIO Rx: 0x3fcb886c   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (80748) SDIO Rx: 0x3fcb887c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80757) SDIO Rx: 0x3fcb888c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80767) SDIO Rx: 0x3fcb889c   00 00 00 00                                       |....|
I (80776) Rx Cmd: 0x3fcb886c   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (80786) Rx Cmd: 0x3fcb887c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80795) Rx Cmd: 0x3fcb888c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80805) Rx Cmd: 0x3fcb889c   00 00 00 00                                       |....|
I (80814) FW_MAIN: Scan request

I (80838) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80839) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (80855) FW_SPI: 0x3fc9e96c   00 00 02 00 08 00 0c 00  1c 00 00 00 04 02 00 00  |................|
I (80857) FW_SPI: 0x3fc9e97c   00 00 00 00 64 e9 c9 3f  54 00 00 00 88 e9 c9 3f  |....d..?T......?|
I (80872) FW_SPI: 0x3fc9e19c   00 00 03 00 63 01 0c 00  44 49 00 00 01 01 5f 01  |....c...DI...._.|
I (80876) FW_SPI: 0x3fc9e1ac   26 5a 4c 99 cd 0a 05 01  b8 ff ff ff 9c 48 d1 04  |&ZL..........H..|
I (80889) FW_SPI: 0x3fcb8860   00 00 03 00 5c 01 0c 00  f7 45 00 00 01 01 58 01  |....\....E....X.|
I (80895) FW_SPI: 0x3fcb8870   24 5a 4c 99 cd 0a 05 01  b8 ff ff ff cc 61 d1 04  |$ZL..........a..|
I (82051) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (82051) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (82068) FW_SPI: 0x3fc9e258   00 00 03 00 57 01 0c 00  a3 46 00 00 01 01 53 01  |....W....F....S.|
I (82069) FW_SPI: 0x3fc9e268   26 5a 4c 99 d2 4a 05 0b  c5 ff ff ff 6e bd e3 04  |&ZL..J......n...|

The logs from the host :

[ 3498.090000] esp32_spi: spi_dev_init: ESP32 peripheral is registered to SPI bus [1],chip select [0], SPI Clock [1]
[ 3499.560000] spi_data_ready_interrupt_handler
[ 3499.560000] spi_interrupt_handler
[ 3499.580000] esp32_spi: process_esp_bootup_event: Received ESP bootup event
[ 3499.580000] spi_interrupt_handler
[ 3499.590000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 3
[ 3499.600000] esp32_spi: esp_validate_chipset: Chipset=ESP32-C3 ID=05 detected over SPI
[ 3499.610000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 2
[ 3499.610000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 0
[ 3499.620000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 1
[ 3499.630000] esp32_spi: process_fw_data: ESP chipset's last reset cause:
[ 3499.630000] esp32_spi: print_reset_reason: POWERON_RESET
[ 3499.640000] esp32_spi: check_esp_version: ESP Firmware version: 1.0.3
[ 3499.650000] esp32_spi: esp_reg_notifier: Driver init is ongoing
[ 3499.710000] spi_data_ready_interrupt_handler
[ 3499.730000] spi_interrupt_handler
[ 3499.750000] spi_interrupt_handler
[ 3499.760000] spi_interrupt_handler
[ 3499.810000] spi_data_ready_interrupt_handler
[ 3499.830000] spi_interrupt_handler
[ 3499.840000] spi_interrupt_handler
[ 3499.870000] spi_interrupt_handler
[ 3499.940000] spi_data_ready_interrupt_handler
[ 3499.960000] spi_interrupt_handler
[ 3499.980000] spi_interrupt_handler
[ 3500.200000] esp32_spi: init_bt: ESP Bluetooth init
[ 3500.200000] esp32_spi: print_capabilities: Capabilities: 0xe8. Features supported are:
[ 3500.210000] esp32_spi: print_capabilities:    * WLAN on SPI
[ 3500.220000] esp32_spi: print_capabilities:    * BT/BLE
[ 3500.220000] esp32_spi: print_capabilities:      - HCI over SPI
[ 3500.230000] esp32_spi: print_capabilities:      - BLE only
[ 3500.250000] spi_interrupt_handler
[ 3500.280000] spi_data_ready_interrupt_handler
[ 3500.300000] spi_interrupt_handler
[ 3500.310000] spi_interrupt_handler
[ 3500.330000] spi_interrupt_handler
[ 3500.360000] spi_data_ready_interrupt_handler
[ 3500.380000] spi_interrupt_handler
[ 3500.410000] spi_interrupt_handler
[ 3500.450000] spi_data_ready_interrupt_handler
[ 3500.460000] spi_interrupt_handler
[ 3500.480000] spi_interrupt_handler
[ 3500.530000] spi_data_ready_interrupt_handler
[ 3500.550000] spi_interrupt_handler
[ 3500.560000] spi_interrupt_handler
[ 3500.580000] spi_interrupt_handler
[ 3500.610000] spi_data_ready_interrupt_handler
[ 3502.640000] Bluetooth: hci0: Opcode 0x1002 failed: -110
[ 3578.640000] spi_interrupt_handler
[ 3578.740000] spi_data_ready_interrupt_handler
[ 3578.750000] spi_interrupt_handler
[ 3578.770000] spi_interrupt_handler
[ 3578.790000] spi_interrupt_handler
[ 3578.800000] spi_interrupt_handler
[ 3579.950000] spi_data_ready_interrupt_handler
[ 3579.970000] spi_interrupt_handler

The command I ran from the host to get these logs :

modprobe esp32_spi resetpin=97 clockspeed=1
# ip link show espsta0
# ip link set dev espsta0 up
12: espsta0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
    link/ether f0:f5:bd:f6:80:c8 brd ff:ff:ff:ff:ff:ff
# iw espsta0 scan

I'm wondering if you have any ideas :D I running out of idea now.

mantriyogesh commented 1 week ago

Thank you @naguirre , for detailed debug and following the documentation. Much appreciated..

I still see that you had to run the different SPI modes at slave and host. I highly suspect the timing issues. How to confirm?

Enable checksum at slave and try to run raw throughput test in TX and RX using transport test.

Additionally, you can change the logs to info and verify the tx from esp matches to rx at host and vice versa. esp logs you already know. host side logs:

host tx: https://github.com/espressif/esp-hosted/blob/b1422afc4fe0a2ea1ffd6b5459edddc4d968ee71/esp_hosted_ng/host/spi/esp_spi.c#L322 You can optionally add log in 'else' case (As spi is full duplex, when host doesn't have any valid data to tx while doing rx from slave, it would send dummy data, which would be ignored at receiver end)

host rx: After spi_sync_transfer() att rx print at: https://github.com/espressif/esp-hosted/blob/b1422afc4fe0a2ea1ffd6b5459edddc4d968ee71/esp_hosted_ng/host/spi/esp_spi.c#L351 add

esp_hex_dump_verbose("Rx: ", trans.rx_buf, 32);

This would help you analyse any mismatch between the tx and rx message either side. As you are enabling checksum (using idf.py menuconfig at slave), if any message silently getting ignored would be printing error, tto understand any underlying transport timing issue.

mantriyogesh commented 1 week ago

I can see the scan request was sent from host to slave. but cannot confirm if the slave tx (i.e. host rx is correctly populated). Above logging would clear the things up.

naguirre commented 6 days ago

Hello, Thanks for your answer!

I checked that the checksum is activated on the esp side. I guess it was already the case, as I can see CONFIG_ESP_SPI_CHECKSUM=y in the sdkconfig file of the network adapter firmware. I added logs host side :

[  956.970000] esp32_spi: esp_reset: Triggering ESP reset.
[  957.190000] >>>>>>>>>> ESP32 SPI Driver
[  957.190000] esp32_spi: spi_dev_init: I am here
[  957.200000] esp32_spi: spi_dev_init: ESP32 peripheral is registered to SPI bus [1],chip select [0], SPI Clock [10]
[  958.670000] spi_data_ready_interrupt_handler
[  958.670000] spi_interrupt_handler
[  958.680000] esp_spi_work
[  958.680000] tx: 05314711: 00 4e c9 3f 18 fe c7 3f 00 00 00 00 00 00 00 00  .N.?...?........
[  958.690000] tx: 019f74f5: 00 00 00 00 00 00 00 00 01 00 00 00 01 00 00 00  ................
[  958.700000] rx: 26e9af94: 03 00 00 00 1a 00 0c 00 52 01 00 00 01 00 13 00  ........R.......
[  958.710000] spi_interrupt_handler
[  958.710000] rx: 692066d0: 12 00 00 00 03 01 05 02 01 01 00 01 e8 01 07 01  ................
[  958.720000] esp_spi_work
[  958.720000] esp32_spi: process_esp_bootup_event: Received ESP bootup event
[  958.730000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 3
[  958.740000] esp32_spi: esp_validate_chipset: Chipset=ESP32-C3 ID=05 detected over SPI
[  958.740000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 2
[  958.750000] esp32_spi: adjust_spi_clock: ESP Reconfigure SPI CLK to 1 MHz
[  958.760000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 0
[  958.760000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 1
[  958.770000] esp32_spi: process_fw_data: ESP chipset's last reset cause:
[  958.780000] esp32_spi: print_reset_reason: POWERON_RESET
[  958.780000] esp32_spi: check_esp_version: ESP Firmware version: 1.0.3
[  958.790000] esp32_spi: esp_reg_notifier: Driver init is ongoing
[  958.800000] esp32_spi: esp_cfg80211_add_iface: Updated priv[0] to c18ec480
[  958.800000] esp32_spi: esp_cmd_work: Processing Command [0x1]
[  958.800000] esp_spi_work
[  958.800000] tx: aaaeb17c: 00 00 01 00 08 00 0c 00 16 00 00 00 01 00 00 00  ................
[  958.810000] tx: ff07565a: 00 00 00 00 02 7c 42 12 71 b1 94 73 39 d7 24 7c  .....|B.q..s9.$|
[  958.840000] rx: b697a9b5: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  958.850000] rx: 5c82b24d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  958.880000] spi_data_ready_interrupt_handler
[  958.880000] esp_spi_work
[  958.890000] tx: f500cf2c: e4 e2 27 92 f1 d0 e2 df c5 5b 1c fe 86 7d 3e e0  ..'......[...}>.
[  958.890000] tx: 97f1166c: 13 f4 a1 59 f0 70 90 d9 f1 dc 00 de 10 db 6f 0c  ...Y.p........o.
[  958.920000] rx: b22f6956: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  958.920000] spi_interrupt_handler
[  958.930000] rx: 957f2dd7: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  958.940000] esp_spi_work
[  958.940000] tx: a0efded6: fd 64 e6 29 44 fe 0c 9d 63 0d 12 de 31 ba ac 3b  .d.)D...c...1..;
[  958.950000] tx: f5c9f57e: 68 3d 5a 2a a0 af 0d 27 56 d3 ba b8 e3 1f 18 47  h=Z*...'V......G
[  958.980000] rx: 01716a3d: 00 00 02 00 08 00 0c 00 19 00 00 00 01 02 00 00  ................
[  958.980000] spi_interrupt_handler
[  958.990000] rx: f516340a: 00 00 00 00 64 e9 c9 3f 54 00 00 00 88 e9 c9 3f  ....d..?T......?
[  959.000000] esp32_spi: process_rx_packet: Checksum enabled
[  959.000000] esp_spi_work
[  959.010000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0x1]
[  959.010000] esp32_spi: esp_cmd_work: Processing Command [0x3]
[  959.010000] esp_spi_work
[  959.010000] tx: 71e93f08: 00 00 01 00 08 00 0c 00 18 00 00 00 03 00 00 00  ................
[  959.020000] tx: 8a4ce7d9: 00 00 00 00 f3 0d 19 1e f6 7b b8 c1 86 a1 2b 98  .........{....+.
[  959.040000] rx: 56c04c4d: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.040000] spi_interrupt_handler
[  959.050000] rx: 0a609169: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.060000] esp_spi_work
[  959.080000] spi_data_ready_interrupt_handler
[  959.090000] esp_spi_work
[  959.090000] tx: b78c14fa: 36 7c 8b a0 9f 19 c1 72 40 fe 5f e1 1c 3f 78 49  6|.....r@._..?xI
[  959.100000] tx: 4cff4a9a: e7 f8 e9 9c ee cd 11 e5 08 bf 75 5b 47 3b d6 ad  ..........u[G;..
[  959.120000] rx: 9825e848: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.120000] spi_interrupt_handler
[  959.130000] rx: 2932b7ed: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.140000] esp_spi_work
[  959.150000] tx: 296c4a53: 47 11 ed f1 06 af c7 9b 96 ae 84 4c a5 28 96 18  G..........L.(..
[  959.160000] tx: aae17cc2: 50 14 3a bf c2 c9 b3 e9 fa 55 ce bb fc a6 e5 ca  P.:......U......
[  959.180000] rx: ad44e1a0: 00 00 02 00 10 00 0c 00 09 05 00 00 03 02 06 00  ................
[  959.180000] spi_interrupt_handler
[  959.190000] rx: 970e5bd8: 00 00 00 00 f0 f5 bd f6 80 c8 00 00 9d 05 00 00  ................
[  959.200000] esp32_spi: process_rx_packet: Checksum enabled
[  959.210000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[  959.210000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0x3]
[  959.210000] esp32_spi: esp_cfg80211_get_tx_power:
[  959.220000] esp32_spi: esp_cmd_work: Processing Command [0xF]
[  959.220000] esp_spi_work
[  959.220000] tx: 2e2d0621: 00 00 01 00 0c 00 0c 00 28 00 00 00 0f 00 00 00  ........(.......
[  959.230000] tx: d4e674ca: 00 00 00 00 00 00 00 00 f7 13 e6 f3 b6 ed 22 5f  .............."_
[  959.260000] rx: 78188c8a: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.260000] spi_interrupt_handler
[  959.270000] rx: 90bbc842: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.280000] esp_spi_work
[  959.330000] spi_data_ready_interrupt_handler
[  959.330000] esp_spi_work
[  959.330000] tx: 9cb36790: 4a 65 31 97 bf e0 b9 ec b4 e6 ca 39 85 5e e0 27  Je1........9.^.'
[  959.340000] tx: 1b3a6989: 46 46 a7 f7 34 95 f7 c7 43 26 e0 06 72 43 ce 57  FF..4...C&..rC.W
[  959.370000] rx: 00e16c2f: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.370000] spi_interrupt_handler
[  959.380000] rx: 66db7e7d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.390000] esp_spi_work
[  959.390000] tx: 4bc1390c: c2 db d0 57 07 fd 3b b8 2d d8 ed 6e 48 56 3a ec  ...W..;.-..nHV:.
[  959.400000] tx: f92fbcd5: 76 51 c6 1f 8a 98 5e 19 f7 a9 fe 82 60 9a 0e cb  vQ....^.....`...
[  959.420000] rx: 945b6b37: 00 00 02 00 0c 00 0c 00 7b 00 00 00 0f 02 00 00  ........{.......
[  959.420000] spi_interrupt_handler
[  959.430000] rx: 0c0e4fea: 00 00 00 00 50 00 00 00 54 00 00 00 88 e9 c9 3f  ....P...T......?
[  959.440000] esp32_spi: process_rx_packet: Checksum enabled
[  959.450000] esp_spi_work
[  959.450000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[  959.450000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0xF]
[  959.670000] esp32_spi: init_bt: ESP Bluetooth init
[  959.670000] esp32_spi: print_capabilities: Checksum enabled
[  959.680000] esp32_spi: print_capabilities: Capabilities: 0xe8. Features supported are:
[  959.690000] esp32_spi: print_capabilities:    * WLAN on SPI
[  959.690000] esp32_spi: print_capabilities:    * BT/BLE
[  959.700000] esp32_spi: print_capabilities:      - HCI over SPI
[  959.700000] esp32_spi: print_capabilities:      - BLE only
[  959.720000] bt_tx: b7510704: 03 0c 00                                         ...
[  959.720000] esp_spi_work
[  959.730000] tx: 8cb82084: 02 00 00 00 03 00 0d 00 22 00 00 00 01 03 0c 00  ........".......
[  959.730000] tx: 867ad63e: 73 70 69 3a 20 70 72 69 6e 74 5f 63 61 70 61 62  spi: print_capab
[  959.760000] rx: 7dec32a1: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.760000] spi_interrupt_handler
[  959.770000] rx: 61db5b86: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.780000] esp_spi_work
[  959.780000] spi_data_ready_interrupt_handler
[  959.790000] esp_spi_work
[  959.790000] tx: ae9b3d51: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.800000] tx: 7abe249b: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.820000] rx: 17c32eb5: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.820000] spi_interrupt_handler
[  959.830000] rx: 98e1619a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.840000] esp_spi_work
[  959.850000] tx: dba468a1: a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5  ................
[  959.860000] tx: 70d4e197: a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5 a5  ................
[  959.880000] rx: 8ce101a0: 02 00 00 00 07 00 0c 00 3f 00 00 00 04 0e 04 05  ........?.......
[  959.880000] spi_interrupt_handler
[  959.890000] rx: 1ad6cfb6: 03 0c 00 3f 64 e9 c9 3f 54 00 00 00 88 e9 c9 3f  ...?d..?T......?
[  959.900000] esp32_spi: process_rx_packet: Checksum enabled
[  959.910000] bt_tx: b9c7f3a2: 03 10 00                                         ...
[  959.920000] esp_spi_work
[  959.920000] tx: e1b72c6e: 02 00 00 00 03 00 0d 00 26 00 00 00 01 03 10 00  ........&.......
[  959.930000] tx: 772fa172: 73 70 69 3a 20 70 72 69 6e 74 5f 63 61 70 61 62  spi: print_capab
[  959.950000] rx: a2522bd7: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.950000] spi_interrupt_handler
[  959.960000] rx: 96d8fa3d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.970000] esp_spi_work
[  959.980000] spi_data_ready_interrupt_handler
[  959.980000] esp_spi_work
[  959.990000] tx: 7eb04fc0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  959.990000] tx: da42f153: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.020000] rx: aff4d59a: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.030000] spi_interrupt_handler
[  960.030000] rx: db2a57c6: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.040000] esp_spi_work
[  960.040000] tx: 43d08712: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.050000] tx: 5bbd3cf8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.080000] rx: 6e30ffde: 02 00 00 00 0f 00 0c 00 b3 00 00 00 04 0e 0c 05  ................
[  960.080000] spi_interrupt_handler
[  960.090000] rx: 5ef16e86: 03 10 00 00 00 00 00 60 00 00 00 3f 9d 05 00 00  .......`...?....
[  960.100000] esp32_spi: process_rx_packet: Checksum enabled
[  960.100000] bt_tx: 5fc25d91: 01 10 00                                         ...
[  960.110000] esp_spi_work
[  960.120000] tx: 6a228689: 02 00 00 00 03 00 0d 00 24 00 00 00 01 01 10 00  ........$.......
[  960.120000] tx: 91540a08: 64 36 63 66 62 36 3a 20 30 33 20 30 63 20 30 30  d6cfb6: 03 0c 00
[  960.150000] rx: 7ca78919: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.150000] spi_interrupt_handler
[  960.160000] rx: c726418e: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.170000] esp_spi_work
[  960.170000] spi_data_ready_interrupt_handler
[  960.180000] esp_spi_work
[  960.180000] tx: aaa3b478: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.190000] tx: b17bdfca: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.210000] rx: 9b5b94be: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.210000] spi_interrupt_handler
[  960.230000] rx: 71691ad4: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.240000] esp_spi_work
[  960.240000] tx: 056d10dc: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.250000] tx: f7be1d88: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.270000] rx: eabc9d31: 02 00 00 00 0f 00 0c 00 76 01 00 00 04 0e 0c 05  ........v.......
[  960.270000] spi_interrupt_handler
[  960.280000] rx: b76bdeab: 01 10 00 09 16 00 09 e5 02 16 00 3f 9d 05 00 00  ...........?....
[  960.290000] esp32_spi: process_rx_packet: Checksum enabled
[  960.300000] bt_tx: 294c0cbb: 09 10 00                                         ...
[  960.310000] esp_spi_work
[  960.310000] tx: 7b5a6c2a: 02 00 00 00 03 00 0d 00 2c 00 00 00 01 09 10 00  ........,.......
[  960.320000] tx: 01e491af: 66 31 36 65 38 36 3a 20 30 33 20 31 30 20 30 30  f16e86: 03 10 00
[  960.340000] rx: acc2e9c0: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.340000] spi_interrupt_handler
[  960.350000] rx: 7eafa854: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.360000] esp_spi_work
[  960.370000] spi_data_ready_interrupt_handler
[  960.370000] esp_spi_work
[  960.380000] tx: 74217197: 00 00 02 00 08 00 0c 00 00 00 00 00 07 02 00 00  ................
[  960.380000] tx: 6270e9ff: 00 00 00 00 a5 05 00 00 64 b9 c9 3f 64 b9 c9 3f  ........d..?d..?
[  960.410000] rx: c501414f: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.410000] spi_interrupt_handler
[  960.420000] rx: 51e66092: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.430000] esp_spi_work
[  960.430000] tx: 070611e4: 03 00 00 00 1a 00 0c 00 52 01 00 00 01 00 13 00  ........R.......
[  960.440000] tx: b47addbc: 12 00 00 00 03 01 05 02 01 01 00 01 e8 01 07 01  ................
[  960.470000] rx: 30549faf: 02 00 00 00 0d 00 0c 00 37 05 00 00 04 0e 0a 05  ........7.......
[  960.470000] spi_interrupt_handler
[  960.480000] rx: 971f1ce8: 09 10 00 ca 80 f6 bd f5 f0 df c9 3f 9d 05 00 00  ...........?....
[  960.490000] esp32_spi: process_rx_packet: Checksum enabled
[  960.490000] bt_tx: b7510704: 02 10 00                                         ...
[  960.500000] esp_spi_work
[  960.500000] tx: df005ec4: 02 00 00 00 03 00 0d 00 25 00 00 00 01 02 10 00  ........%.......
[  960.510000] tx: 19d06440: 36 62 64 65 61 62 3a 20 30 31 20 31 30 20 30 30  6bdeab: 01 10 00
[  960.540000] rx: 5422f93e: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.540000] spi_interrupt_handler
[  960.550000] rx: d38bf340: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.560000] esp_spi_work
[  960.560000] spi_data_ready_interrupt_handler
[  960.570000] esp_spi_work
[  960.570000] tx: a6376a62: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.580000] tx: bca5abbd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.600000] rx: e2bc9133: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  960.610000] rx: 516b1ad3: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  962.560000] Bluetooth: hci0: Opcode 0x1002 failed: -110
[  988.220000] esp32_spi: update_mac_filter: Port is not open yet, skipping mac filter update
[  988.220000] esp32_spi: update_mac_filter: Port is not open yet, skipping mac filter update
[  988.250000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  988.390000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  989.200000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  990.240000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  990.240000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  990.800000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  994.400000] esp32_spi: esp_hard_start_xmit: Port not yet open
[  997.360000] esp32_spi: esp_cfg80211_scan:
[  997.360000] esp32_spi: esp_cmd_work: Processing Command [0x4]
[  997.360000] esp_spi_work
[  997.360000] tx: 7b25477e: 00 00 01 00 34 00 0c 00 3f 06 00 00 04 00 00 00  ....4...?.......
[  997.370000] tx: 2999ffe2: 00 00 00 00 ff ff ff ff ff ff 00 00 00 00 00 00  ................
[  997.390000] rx: 009a5b70: 02 00 00 00 47 00 0c 00 ea 0c 00 00 04 0e 44 05  ....G.........D.
[  997.390000] spi_interrupt_handler
[  997.400000] rx: eddc5efa: 02 10 00 20 00 80 00 00 c0 00 00 00 00 e4 00 00  ... ............
[  997.420000] esp32_spi: process_rx_packet: Checksum enabled
[  997.420000] esp_spi_work
[  997.480000] spi_data_ready_interrupt_handler
[  997.490000] esp_spi_work
[  997.490000] tx: be6a8379: 82 d3 04 42 00 00 00 00 00 00 00 00 00 00 00 00  ...B............
[  997.500000] tx: 937738a3: 10 00 00 00 94 df c9 3f 64 b9 c9 3f 00 00 00 00  .......?d..?....
[  997.520000] rx: 3046aeec: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.520000] spi_interrupt_handler
[  997.540000] rx: 4e9eb644: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.550000] esp_spi_work
[  997.550000] tx: 656ab89d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.560000] tx: cb6f73dd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.580000] rx: a90300e9: 00 00 02 00 08 00 0c 00 1c 00 00 00 04 02 00 00  ................
[  997.580000] spi_interrupt_handler
[  997.590000] rx: ff90e6d7: 00 00 00 00 64 e9 c9 3f 54 00 00 00 88 e9 c9 3f  ....d..?T......?
[  997.600000] esp32_spi: process_rx_packet: Checksum enabled
[  997.610000] esp_spi_work
[  997.610000] tx: ebaf2e75: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.620000] tx: d29c7cb3: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.640000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[  997.640000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0x4]
[  997.660000] rx: df2f9a57: 00 00 03 00 69 01 0c 00 f6 47 00 00 01 01 65 01  ....i....G....e.
[  997.660000] spi_interrupt_handler
[  997.670000] rx: 916539d9: 26 5a 4c 99 cd 0a 08 01 bb ff ff ff c5 8f 69 02  &ZL...........i.
[  997.700000] esp32_spi: process_rx_packet: Checksum enabled
[  997.700000] esp_spi_work
[  997.700000] tx: a349dc76: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.710000] tx: 28a1987d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  997.740000] rx: 859071ed: 80 00 03 00 5c 01 0c 00 4c 44 00 00 01 01 58 01  ....\...LD....X.
[  997.740000] spi_interrupt_handler
[  997.750000] rx: 13d5dc15: 24 5a 4c 99 cd 0a 05 01 ba ff ff ff 4b 9c 69 02  $ZL.........K.i.
[  997.760000] esp32_spi: process_rx_packet: Checksum enabled
[  997.770000] esp32_spi: process_rx_packet: Checksum mismatch: 44cc 444c
[  997.770000] esp_spi_work
[  998.150000] spi_data_ready_interrupt_handler
[  998.150000] esp_spi_work
[  998.150000] tx: 7f169878: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.160000] tx: a3108852: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.190000] rx: 443cf9e7: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.190000] spi_interrupt_handler
[  998.200000] rx: b53f0ffa: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.210000] esp_spi_work
[  998.210000] tx: 22228988: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.220000] tx: 6ab6ce93: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.240000] rx: 3cd1b5ad: 00 00 03 00 bc 01 0c 00 79 6a 00 00 01 01 b8 01  ........yj......
[  998.250000] spi_interrupt_handler
[  998.250000] rx: c868d4af: 72 4d d4 11 57 cb 08 06 a6 ff ff ff ac 9b 73 02  rM..W.........s.
[  998.260000] esp32_spi: process_rx_packet: Checksum enabled
[  998.270000] esp_spi_work
[  998.700000] spi_data_ready_interrupt_handler
[  998.700000] esp_spi_work
[  998.710000] tx: 0245705f: 02 00 00 00 0d 00 0c 00 00 00 00 00 04 0e 0a 05  ................
[  998.710000] tx: 5943bd8e: 09 10 00 ca 80 f6 bd f5 f0 df c9 3f 9d 05 00 00  ...........?....
[  998.740000] rx: 1a60ddea: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.740000] spi_interrupt_handler
[  998.750000] rx: 18aec1dc: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.760000] esp_spi_work
[  998.760000] tx: 6c1e269a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.770000] tx: 39d00112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[  998.790000] rx: fac5c8bc: 00 00 03 00 50 01 0c 00 f1 42 00 00 01 01 4c 01  ....P....B....L.
[  998.800000] rx: b2411e1b: 24 5a 4c 99 d2 4a 05 0b c4 ff ff ff 7b 0a 7c 02  $ZL..J......{.|.
[  998.810000] esp32_spi: process_rx_packet: Checksum enabled
[ 1003.360000] esp32_spi: esp_hard_start_xmit: Port not yet open

esp logs :

ESP-ROM:esp32c3-api1-20210207
Build:Feb  7 2021
rst:0x1 (POWERON),boot:0xa (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x170c
load:0x403cc710,len:0x968
load:0x403ce710,len:0x2f9c
entry 0x403cc710
I (30) boot: ESP-IDF v5.1.3-dirty 2nd stage bootloader
I (30) boot: compile time Jun 26 2024 15:24:08
I (30) boot: chip revision: v0.4
I (34) boot.esp32c3: SPI Speed      : 80MHz
I (39) boot.esp32c3: SPI Mode       : DIO
I (43) boot.esp32c3: SPI Flash Size : 4MB
I (48) boot: Enabling RNG early entropy source...
I (53) boot: Partition Table:
I (57) boot: ## Label            Usage          Type ST Offset   Length
I (64) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (72) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (79) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (87) boot:  3 factory          factory app      00 00 00010000 00100000
I (94) boot:  4 ota_0            OTA app          00 10 00110000 00100000
I (102) boot:  5 ota_1            OTA app          00 11 00210000 00100000
I (109) boot: End of partition table
I (113) boot: Defaulting to factory image
I (118) esp_image: segment 0: paddr=00010020 vaddr=3c090020 size=1faf8h (129784) map
I (148) esp_image: segment 1: paddr=0002fb20 vaddr=3fc94600 size=004f8h (  1272) load
I (148) esp_image: segment 2: paddr=00030020 vaddr=42000020 size=8be94h (573076) map
I (246) esp_image: segment 3: paddr=000bbebc vaddr=3fc94af8 size=02de4h ( 11748) load
I (249) esp_image: segment 4: paddr=000beca8 vaddr=40380000 size=145d8h ( 83416) load
I (274) boot: Loaded app from partition at offset 0x10000
I (275) boot: Disabling RNG early entropy source...
I (286) cpu_start: Unicore app
I (286) cpu_start: Pro cpu up.
I (295) cpu_start: Pro cpu start user code
I (295) cpu_start: cpu freq: 160000000 Hz
I (295) cpu_start: Application information:
I (298) cpu_start: Project name:     network_adapter
I (304) cpu_start: App version:      release/ng-v1.0.2-265-gb5cca72-
I (311) cpu_start: Compile time:     Jun 26 2024 15:24:00
I (317) cpu_start: ELF file SHA256:  890be7408f309dbb...
I (323) cpu_start: ESP-IDF:          v5.1.3-dirty
I (328) cpu_start: Min chip rev:     v0.3
I (333) cpu_start: Max chip rev:     v1.99
I (338) cpu_start: Chip rev:         v0.4
I (342) heap_init: Initializing. RAM available for dynamic allocation:
I (350) heap_init: At 3FC9B950 len 000246B0 (145 KiB): DRAM
I (356) heap_init: At 3FCC0000 len 0001C710 (113 KiB): DRAM/RETENTION
I (363) heap_init: At 3FCDC710 len 00002950 (10 KiB): DRAM/RETENTION/STACK
I (370) heap_init: At 50000010 len 00001FD8 (7 KiB): RTCRAM
I (378) spi_flash: detected chip: generic
I (381) spi_flash: flash io: dio
I (386) sleep: Configure to isolate all GPIO pins in sleep state
I (392) sleep: Enable automatic switching of GPIO sleep configuration
I (399) coexist: coex firmware version: 77cd7f8
I (405) coexist: coexist rom version 9387209
I (410) app_start: Starting scheduler on CPU0
I (414) main_task: Started on CPU0
I (418) main_task: Calling app_main()
I (422) stats: *********************************************************************
I (430) stats:                 ESP-Hosted Firmware version :: 1.0.3
I (439) stats:                 Transport used :: SPI only
I (447) stats: *********************************************************************
I (456) FW_MAIN: Supported features are:
I (460) FW_MAIN: - WLAN over SPI
I (464) FW_BT: - BT/BLE
I (467) FW_BT:    - HCI Over SPI
I (471) FW_BT:    - BLE only
I (475) FW_MAIN: Capabilities: 0xe8
pp rom version: 9387209
net80211 rom version: 9387209
I (485) wifi:wifi driver task: 3fca1df0, prio:23, stack:6656, core=0
I (491) wifi:wifi firmware version: e3cf69a
I (494) wifi:wifi certification version: v7.0
I (498) wifi:config NVS flash: disabled
I (502) wifi:config nano formating: disabled
I (506) wifi:Init data frame dynamic rx buffer num: 40
I (511) wifi:Init static rx mgmt buffer num: 5
I (515) wifi:Init management short buffer num: 32
I (519) wifi:Init dynamic tx buffer num: 40
I (523) wifi:Init static tx FG buffer num: 2
I (527) wifi:Init static rx buffer size: 1600
I (531) wifi:Init static rx buffer num: 20
I (535) wifi:Init dynamic rx buffer num: 40
I (539) wifi_init: rx ba win: 32
I (543) wifi_init: tcpip mbox: 32
I (547) wifi_init: udp mbox: 6
I (550) wifi_init: tcp mbox: 6
I (554) wifi_init: tcp tx win: 5760
I (558) wifi_init: tcp rx win: 5760
I (562) wifi_init: tcp mss: 1440
I (566) wifi_init: WiFi IRAM OP enabled
I (571) wifi_init: WiFi RX IRAM OP enabled
I (576) BLE_INIT: BT controller compile version [9359a4d]
I (582) BLE_INIT: Bluetooth MAC: f0:f5:bd:f6:80:ca

I (588) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21
I (632) FW_MAIN: ESP Bluetooth MAC addr: f0-f5-bd-f6-80-ca

I (633) FW_SPI: Using SPI interface
I (633) gpio: GPIO[3]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (641) gpio: GPIO[4]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
SPI Slave mode : 1
I (1653) FW_MAIN: Initial set up done
I (1653) main_task: Returned from app_main()
I (1682) FW_SPI: 0x3fcb8860   03 00 00 00 1a 00 0c 00  52 01 00 00 01 00 13 00  |........R.......|
I (1683) FW_SPI: 0x3fcb8870   12 00 00 00 03 01 05 02  01 01 00 01 e8 01 07 01  |................|
I (1826) FW_SPI: 0x3fc9d8dc   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1826) FW_SPI: 0x3fc9d8ec   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1835) SDIO Rx: 0x3fc9df2c   01 00 00 00 00 00 00 00                           |........|
I (1843) Rx Cmd: 0x3fc9df2c   01 00 00 00 00 00 00 00                           |........|
I (1852) FW_MAIN: INIT Interface command

I (1858) wifi:mode : sta (f0:f5:bd:f6:80:c8)
I (1861) wifi:enable tsf
I (1863) FW_CMD: Wifi Sta mode set

I (1904) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1904) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (1960) FW_SPI: 0x3fc9e96c   00 00 02 00 08 00 0c 00  19 00 00 00 01 02 00 00  |................|
I (1961) FW_SPI: 0x3fc9e97c   00 00 00 00 64 e9 c9 3f  54 00 00 00 88 e9 c9 3f  |....d..?T......?|
I (2028) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2028) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2037) SDIO Rx: 0x3fcb8eb0   03 00 00 00 00 00 00 00                           |........|
I (2045) Rx Cmd: 0x3fcb8eb0   03 00 00 00 00 00 00 00                           |........|
I (2054) FW_MAIN: Get MAC command

I (2058) FW_CMD: 0x3fcb74e0   f0 f5 bd f6 80 c8                                 |......|
I (2108) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2108) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2165) FW_SPI: 0x3fc9dfa4   00 00 02 00 10 00 0c 00  09 05 00 00 03 02 06 00  |................|
I (2165) FW_SPI: 0x3fc9dfb4   00 00 00 00 f0 f5 bd f6  80 c8 00 00 9d 05 00 00  |................|
I (2240) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2241) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2250) SDIO Rx: 0x3fcb9ff8   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (2258) Rx Cmd: 0x3fcb9ff8   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (2267) FW_MAIN: Tx power command

I (2272) MAC Filter: 0x3fc9843c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2282) MAC Filter: 0x3fc9844c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2292) MAC Filter: 0x3fc9845c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2301) MAC Filter: 0x3fc9846c   00                                                |.|
I (2350) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2350) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2407) FW_SPI: 0x3fc9e96c   00 00 02 00 0c 00 0c 00  7b 00 00 00 0f 02 00 00  |........{.......|
I (2407) FW_SPI: 0x3fc9e97c   00 00 00 00 50 00 00 00  54 00 00 00 88 e9 c9 3f  |....P...T......?|
I (2740) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2741) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2750) SDIO Rx: 0x3fcb8eb1   03 0c 00                                          |...|
I (2758) H->S BT: 0x3fcb8eb1   03 0c 00                                          |...|
I (2808) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2808) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2864) FW_SPI: 0x3fc9e96c   02 00 00 00 07 00 0c 00  3f 00 00 00 04 0e 04 05  |........?.......|
I (2865) FW_SPI: 0x3fc9e97c   03 0c 00 3f 64 e9 c9 3f  54 00 00 00 88 e9 c9 3f  |...?d..?T......?|
I (2936) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2936) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2945) SDIO Rx: 0x3fcb9ff9   03 10 00                                          |...|
I (2953) H->S BT: 0x3fcb9ff9   03 10 00                                          |...|
I (3004) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3005) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3060) FW_SPI: 0x3fc9dfa4   02 00 00 00 0f 00 0c 00  b3 00 00 00 04 0e 0c 05  |................|
I (3060) FW_SPI: 0x3fc9dfb4   03 10 00 00 00 00 00 60  00 00 00 3f 9d 05 00 00  |.......`...?....|
I (3133) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3133) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3142) SDIO Rx: 0x3fcb8eb1   01 10 00                                          |...|
I (3150) H->S BT: 0x3fcb8eb1   01 10 00                                          |...|
I (3200) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3200) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3256) FW_SPI: 0x3fc9dfa4   02 00 00 00 0f 00 0c 00  76 01 00 00 04 0e 0c 05  |........v.......|
I (3257) FW_SPI: 0x3fc9dfb4   01 10 00 09 16 00 09 e5  02 16 00 3f 9d 05 00 00  |...........?....|
I (3328) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3328) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3337) SDIO Rx: 0x3fcb9ff9   09 10 00                                          |...|
I (3345) H->S BT: 0x3fcb9ff9   09 10 00                                          |...|
I (3393) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3394) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3450) FW_SPI: 0x3fc9dfa4   02 00 00 00 0d 00 0c 00  37 05 00 00 04 0e 0a 05  |........7.......|
I (3450) FW_SPI: 0x3fc9dfb4   09 10 00 ca 80 f6 bd f5  f0 df c9 3f 9d 05 00 00  |...........?....|
I (3522) FW_SPI: 0x3fcb8860   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3523) FW_SPI: 0x3fcb8870   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3532) SDIO Rx: 0x3fcb8eb1   02 10 00                                          |...|
I (3539) H->S BT: 0x3fcb8eb1   02 10 00                                          |...|
I (3589) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3590) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40377) FW_SPI: 0x3fc9dff0   02 00 00 00 47 00 0c 00  ea 0c 00 00 04 0e 44 05  |....G.........D.|
I (40378) FW_SPI: 0x3fc9e000   02 10 00 20 00 80 00 00  c0 00 00 00 00 e4 00 00  |... ............|
I (40387) SDIO Rx: 0x3fcb886c   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (40396) SDIO Rx: 0x3fcb887c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40406) SDIO Rx: 0x3fcb888c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40415) SDIO Rx: 0x3fcb889c   00 00 00 00                                       |....|
I (40424) Rx Cmd: 0x3fcb886c   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (40434) Rx Cmd: 0x3fcb887c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40444) Rx Cmd: 0x3fcb888c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40453) Rx Cmd: 0x3fcb889c   00 00 00 00                                       |....|
I (40462) FW_MAIN: Scan request

I (40508) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40508) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (40564) FW_SPI: 0x3fc9e96c   00 00 02 00 08 00 0c 00  1c 00 00 00 04 02 00 00  |................|
I (40564) FW_SPI: 0x3fc9e97c   00 00 00 00 64 e9 c9 3f  54 00 00 00 88 e9 c9 3f  |....d..?T......?|
I (40640) FW_SPI: 0x3fc9e19c   00 00 03 00 69 01 0c 00  f6 47 00 00 01 01 65 01  |....i....G....e.|
I (40640) FW_SPI: 0x3fc9e1ac   26 5a 4c 99 cd 0a 08 01  bb ff ff ff c5 8f 69 02  |&ZL...........i.|
I (40722) FW_SPI: 0x3fcb8860   00 00 03 00 5c 01 0c 00  4c 44 00 00 01 01 58 01  |....\...LD....X.|
I (40723) FW_SPI: 0x3fcb8870   24 5a 4c 99 cd 0a 05 01  ba ff ff ff 4b 9c 69 02  |$ZL.........K.i.|
I (41170) FW_SPI: 0x3fcb9fec   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (41170) FW_SPI: 0x3fcb9ffc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (41226) FW_SPI: 0x3fc9e2b4   00 00 03 00 bc 01 0c 00  79 6a 00 00 01 01 b8 01  |........yj......|
I (41227) FW_SPI: 0x3fc9e2c4   72 4d d4 11 57 cb 08 06  a6 ff ff ff ac 9b 73 02  |rM..W.........s.|
I (41723) FW_SPI: 0x3fcb8ea4   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (41723) FW_SPI: 0x3fcb8eb4   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (41779) FW_SPI: 0x3fc9e2ac   00 00 03 00 50 01 0c 00  f1 42 00 00 01 01 4c 01  |....P....B....L.|
I (41780) FW_SPI: 0x3fc9e2bc   24 5a 4c 99 d2 4a 05 0b  c4 ff ff ff 7b 0a 7c 02  |$ZL..J......{.|.|

There is indeed one checksum mismatch, but I can't see a difference on the SPI TX Side for this frame.

kapilkedawat commented 5 days ago

Hi @naguirre, could you please enable following logs.. https://github.com/espressif/esp-hosted/blob/abe8f45c577f26a88d98528e27dfd027472fa8f4/esp_hosted_ng/esp/esp_driver/network_adapter/main/cmd.c#L653 https://github.com/espressif/esp-hosted/blob/abe8f45c577f26a88d98528e27dfd027472fa8f4/esp_hosted_ng/esp/esp_driver/network_adapter/main/cmd.c#L658

Also please recompile the firmware with debug logging enabled. https://github.com/espressif/esp-idf/blob/master/components/log/Kconfig.level#L26

mantriyogesh commented 5 days ago

In addition to @kapilkedawat response,

Checksum mismatch: 44cc 444c

Is very important issue to handle. This print really means that spi communication is not correct and timing issues are there in between host and slave.

  1. Can you please post setup picture closeup where ESP and host connections are clearly visible?
  2. In addition to logs, can you please take logic analyser reading for pins: SPI 4 pins + Handshake + Data Ready?
naguirre commented 3 days ago

Hello, I can't reproduce the CRC error I had last time. I'm wondering if it was not something really weird that happened only once. Since then, this message never shows up again. Also, I retried the SPI connection with spidev, and it looks really stable, without missing bytes or corrupted ones.

Everything is soldered on a PCBA, ESP32-C3-Mini, and F1c200s. So, I can send a picture, but all the SPi signals are routed on internal layers of the PCB. So that's hard to make a picture of that :D

For the same reason, and because I didn't route Test points, I can't plug a logic analyzer on those signals :/

I activated all the debugging you asked for. Sometimes, I'm able to get the iw espsta0 scan to return the list of Wi-Fis. But I'm not able to get wpa_supplicant working. Most of the time, I get the same result as the one I pushed before, where scanning never returns.

I see this message often: esp32_spi: esp_cmd_work: Busy in another cmd execution Is this something I have to worry about, or is it only a warning not related ?

Here the logs : Host :

[   69.160000] >>>>>>>>>> ESP32 SPI Driver
[   69.160000] esp32_spi: spi_dev_init: I am here
[   69.170000] esp32_spi: spi_dev_init: ESP32 peripheral is registered to SPI bus [1],chip select [0], SPI Clock [10]
[   71.030000] spi_data_ready_interrupt_handler
[   71.030000] spi_interrupt_handler
[   71.040000] esp_spi_work
[   71.040000] tx: c715f386: 7b 8f 3a a7 80 f6 4c 3e 64 43 dc 7b e9 53 21 b6  {.:...L>dC.{.S!.
[   71.050000] tx: 611f8ad4: c3 f4 34 53 7b 89 db 19 03 21 2d db 1b 4b ed d5  ..4S{....!-..K..
[   71.060000] rx: 1fb67d38: 03 00 00 00 1a 00 0c 00 52 01 00 00 01 00 13 00  ........R.......
[   71.070000] spi_interrupt_handler
[   71.070000] rx: d42367f0: 12 00 00 00 03 01 05 02 01 01 00 01 e8 01 07 01  ................
[   71.080000] esp_spi_work
[   71.080000] esp32_spi: process_esp_bootup_event: Received ESP bootup event
[   71.090000] esp32_spi: esp_commands_teardown: Cleaning up command queues
[   71.090000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 3
[   71.100000] esp32_spi: esp_validate_chipset: Chipset=ESP32-C3 ID=05 detected over SPI
[   71.100000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 2
[   71.110000] esp32_spi: adjust_spi_clock: ESP Reconfigure SPI CLK to 1 MHz
[   71.120000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 0
[   71.120000] esp32_spi: process_event_esp_bootup: Bootup Event tag: 1
[   71.130000] esp32_spi: process_fw_data: ESP chipset's last reset cause:
[   71.140000] esp32_spi: print_reset_reason: POWERON_RESET
[   71.140000] esp32_spi: check_esp_version: ESP Firmware version: 1.0.3
[   71.150000] esp32_spi: esp_reg_notifier: Driver init is ongoing
[   71.160000] esp32_spi: esp_cfg80211_add_iface: Updated priv[0] to c18ec480
[   71.160000] esp32_spi: cmd_init_interface: Queueing CMD_INIT_INTERFACE
[   71.170000] esp32_spi: esp_cmd_work: Processing Command [0x1]
[   71.170000] esp_spi_work
[   71.170000] tx: dc25a6d4: 00 00 01 00 08 00 0c 00 16 00 00 00 01 00 00 00  ................
[   71.180000] tx: 32544093: 00 00 00 00 2c e2 7d e6 da cf ef 34 11 83 ac 31  ....,.}....4...1
[   71.200000] rx: 1fd173c3: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.200000] spi_interrupt_handler
[   71.210000] rx: b017a301: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.220000] esp_spi_work
[   71.260000] spi_data_ready_interrupt_handler
[   71.260000] esp_spi_work
[   71.270000] tx: 9e1cdc16: ef 15 03 cf f9 e6 8b a5 e3 89 e3 6a 35 af 54 2b  ...........j5.T+
[   71.270000] tx: b2cec522: da 15 93 57 5a 16 de fc 94 cd 2b 95 01 86 ca 00  ...WZ.....+.....
[   71.300000] rx: 4b2b1a28: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.300000] spi_interrupt_handler
[   71.310000] rx: e3d497fd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.320000] esp_spi_work
[   71.320000] tx: 5fc2a9a4: f0 4c da f3 81 57 4b 07 cf d9 ee 0f 68 73 d1 e8  .L...WK.....hs..
[   71.330000] tx: 4f754aeb: f0 dc 59 97 5b 73 67 5f 6e 65 51 ce c6 f8 bc 3f  ..Y.[sg_neQ....?
[   71.350000] rx: 9c69e883: 00 00 02 00 08 00 0c 00 19 00 00 00 01 02 00 00  ................
[   71.350000] spi_interrupt_handler
[   71.370000] rx: 1946fb4d: 00 00 00 00 c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  .......?T......?
[   71.380000] esp32_spi: process_rx_packet: Checksum enabled
[   71.380000] esp32_spi: process_cmd_resp: Queuing work for command : 0x1
[   71.380000] esp_spi_work
[   71.380000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[   71.380000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0x1]
[   71.380000] esp32_spi: cmd_get_mac: Queueing CMD_GET_MAC
[   71.380000] esp32_spi: esp_cmd_work: Processing Command [0x3]
[   71.380000] esp_spi_work
[   71.390000] tx: 8dad05bc: 00 00 01 00 08 00 0c 00 18 00 00 00 03 00 00 00  ................
[   71.400000] tx: 64564284: 00 00 00 00 3f bc da 1c 73 14 b7 93 ef 5f 7f 6b  ....?...s...._.k
[   71.420000] rx: 6952a4bb: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.420000] spi_interrupt_handler
[   71.430000] rx: 304587b0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.440000] esp_spi_work
[   71.460000] spi_data_ready_interrupt_handler
[   71.460000] esp_spi_work
[   71.470000] tx: 47cef5e9: a3 6d 4d ef 71 18 dd 2f 44 db 9c 8c 51 25 ed 6b  .mM.q../D...Q%.k
[   71.470000] tx: c0f5ddf5: 17 28 79 3a f5 5e b8 ee 1a b6 4b 59 48 e0 67 ee  .(y:.^....KYH.g.
[   71.500000] rx: 58d44d91: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.500000] spi_interrupt_handler
[   71.510000] rx: 5b08f10e: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.520000] esp_spi_work
[   71.520000] tx: 1be87de7: 1f ce 1a b8 0f 7b 9a f2 27 35 43 fb 9c dd 90 53  .....{..'5C....S
[   71.530000] tx: 97aaa1e1: 2f 36 64 d4 8b 63 7f c1 fe 6b 5e 2e e3 dd 92 d6  /6d..c...k^.....
[   71.550000] rx: bce4f9dc: 00 00 02 00 10 00 0c 00 09 05 00 00 03 02 06 00  ................
[   71.550000] spi_interrupt_handler
[   71.560000] rx: f7c348b8: 00 00 00 00 f0 f5 bd f6 80 c8 00 00 9d 05 00 00  ................
[   71.570000] esp32_spi: process_rx_packet: Checksum enabled
[   71.580000] esp32_spi: process_cmd_resp: Queuing work for command : 0x3
[   71.580000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[   71.580000] esp_spi_work
[   71.580000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0x3]
[   71.590000] esp32_spi: esp_cfg80211_get_tx_power:
[   71.590000] esp32_spi: cmd_get_tx_power: Queueing CMD_GET_TXPOWER
[   71.590000] esp32_spi: esp_cmd_work: Processing Command [0xF]
[   71.590000] esp_spi_work
[   71.590000] tx: c92196c0: 00 00 01 00 0c 00 0c 00 28 00 00 00 0f 00 00 00  ........(.......
[   71.600000] tx: 946bd665: 00 00 00 00 00 00 00 00 0d 75 c6 35 37 03 fb cd  .........u.57...
[   71.640000] rx: 919a21d2: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.640000] spi_interrupt_handler
[   71.650000] rx: 8da07c19: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.660000] esp_spi_work
[   71.710000] spi_data_ready_interrupt_handler
[   71.710000] esp_spi_work
[   71.710000] tx: cd039804: a4 ad ee 06 e4 cb 9b 9b 7f eb 7b e4 85 52 6c ff  ..........{..Rl.
[   71.720000] tx: 5722e126: bd e1 84 3a 3b 24 cc 13 e5 a0 9c ab 5b 5b 94 8f  ...:;$......[[..
[   71.750000] rx: aaafe7bb: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.750000] spi_interrupt_handler
[   71.760000] rx: c4754f48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   71.770000] esp_spi_work
[   71.770000] tx: f88ce554: 63 3c 5f 7e 8e b4 46 d0 6f 51 4f 94 ff af 2f 6f  c<_~..F.oQO.../o
[   71.780000] tx: f93899e9: 8e ed c3 b7 b3 3d 2b 64 cc ac 3e f3 9a e3 bc 0c  .....=+d..>.....
[   71.800000] rx: faccdf8f: 00 00 02 00 0c 00 0c 00 7b 00 00 00 0f 02 00 00  ........{.......
[   71.810000] rx: 55db6d18: 00 00 00 00 50 00 00 00 54 00 00 00 e8 eb c9 3f  ....P...T......?
[   71.820000] esp32_spi: process_rx_packet: Checksum enabled
[   71.830000] esp32_spi: process_cmd_resp: Queuing work for command : 0xf
[   71.830000] esp32_spi: esp_cmd_work: Busy in another cmd execution
[   71.830000] esp32_spi: wait_and_decode_cmd_resp: Resp for command [0xF]
[   72.050000] esp32_spi: init_bt: ESP Bluetooth init
[   72.050000] esp32_spi: print_capabilities: Checksum enabled
[   72.060000] esp32_spi: print_capabilities: Capabilities: 0xe8. Features supported are:
[   72.070000] esp32_spi: print_capabilities:    * WLAN on SPI
[   72.070000] esp32_spi: print_capabilities:    * BT/BLE
[   72.080000] esp32_spi: print_capabilities:      - HCI over SPI
[   72.080000] esp32_spi: print_capabilities:      - BLE only
[   72.100000] bt_tx: 4c959dbc: 03 0c 00                                         ...
[   72.100000] esp_spi_work
[   72.110000] tx: 0421e27e: 02 00 00 00 03 00 0d 00 22 00 00 00 01 03 0c 00  ........".......
[   72.110000] tx: 265506fa: 73 70 69 3a 20 70 72 69 6e 74 5f 63 61 70 61 62  spi: print_capab
[   72.140000] rx: 9e1cdc16: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.140000] spi_interrupt_handler
[   72.150000] rx: b2cec522: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.160000] esp_spi_work
[   72.160000] spi_data_ready_interrupt_handler
[   72.170000] esp_spi_work
[   72.170000] tx: 4b2b1a28: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.180000] tx: e3d497fd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.200000] rx: 5fc2a9a4: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.200000] spi_interrupt_handler
[   72.220000] rx: 4f754aeb: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.230000] esp_spi_work
[   72.230000] tx: 9c69e883: 00 00 02 00 08 00 0c 00 00 00 00 00 01 02 00 00  ................
[   72.240000] tx: 1946fb4d: 00 00 00 00 c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  .......?T......?
[   72.260000] rx: 4de58cb7: 02 00 00 00 07 00 0c 00 3f 00 00 00 04 0e 04 05  ........?.......
[   72.260000] spi_interrupt_handler
[   72.270000] rx: a1f67b4c: 03 0c 00 3f c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  ...?...?T......?
[   72.280000] esp32_spi: process_rx_packet: Checksum enabled
[   72.290000] bt_tx: af99e9ac: 03 10 00                                         ...
[   72.300000] esp_spi_work
[   72.300000] tx: 63693644: 02 00 00 00 03 00 0d 00 26 00 00 00 01 03 10 00  ........&.......
[   72.310000] tx: f14b40f7: 73 70 69 3a 20 70 72 69 6e 74 5f 63 61 70 61 62  spi: print_capab
[   72.330000] rx: 81125565: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.330000] spi_interrupt_handler
[   72.340000] rx: bf00e12b: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.350000] esp_spi_work
[   72.360000] spi_data_ready_interrupt_handler
[   72.360000] esp_spi_work
[   72.370000] tx: 3c088738: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.370000] tx: 8476fe3c: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.400000] rx: 6bb6179c: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.400000] spi_interrupt_handler
[   72.410000] rx: 500fc2b8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.420000] esp_spi_work
[   72.420000] tx: cdc73fd0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.430000] tx: 828f4d90: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.450000] rx: f6da712e: 02 00 00 00 0f 00 0c 00 b3 00 00 00 04 0e 0c 05  ................
[   72.460000] rx: 5aab1e70: 03 10 00 00 00 00 00 60 00 00 00 3f 9d 05 00 00  .......`...?....
[   72.470000] esp32_spi: process_rx_packet: Checksum enabled
[   72.480000] bt_tx: a19bfbd0: 01 10 00                                         ...
[   72.490000] esp_spi_work
[   72.490000] tx: 18ef6dad: 02 00 00 00 03 00 0d 00 24 00 00 00 01 01 10 00  ........$.......
[   72.500000] tx: bea5d07e: 66 36 37 62 34 63 3a 20 30 33 20 30 63 20 30 30  f67b4c: 03 0c 00
[   72.520000] rx: 141a316f: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.520000] spi_interrupt_handler
[   72.530000] rx: fb3e1b67: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.540000] esp_spi_work
[   72.550000] spi_data_ready_interrupt_handler
[   72.550000] esp_spi_work
[   72.560000] tx: d2913ea0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.560000] tx: e12d8e2d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.590000] rx: 60f3c8a6: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.590000] spi_interrupt_handler
[   72.600000] rx: f6b3139a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.610000] esp_spi_work
[   72.610000] tx: e3062420: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.620000] tx: 0f8345e9: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.650000] rx: d6ad4d38: 02 00 00 00 0f 00 0c 00 76 01 00 00 04 0e 0c 05  ........v.......
[   72.650000] spi_interrupt_handler
[   72.660000] rx: 5e9786f7: 01 10 00 09 16 00 09 e5 02 16 00 3f 9d 05 00 00  ...........?....
[   72.670000] esp32_spi: process_rx_packet: Checksum enabled
[   72.670000] bt_tx: db46511d: 09 10 00                                         ...
[   72.680000] esp_spi_work
[   72.690000] tx: fecb74f5: 02 00 00 00 03 00 0d 00 2c 00 00 00 01 09 10 00  ........,.......
[   72.690000] tx: 27205947: 64 61 37 31 32 65 3a 20 30 32 20 30 30 20 30 30  da712e: 02 00 00
[   72.720000] rx: 9bfa784d: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.720000] spi_interrupt_handler
[   72.730000] rx: 8c575f1c: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.740000] esp_spi_work
[   72.740000] spi_data_ready_interrupt_handler
[   72.750000] esp_spi_work
[   72.750000] tx: 51ac4459: 9b c2 73 2d da 3f e4 ed 02 bf 37 fc c9 f0 91 de  ..s-.?....7.....
[   72.760000] tx: 8a188b85: be 48 5a fe 33 5f 9b 5e 9f f4 25 6b 9a 86 5c fb  .HZ.3_.^..%k..\.
[   72.780000] rx: 215c7ca4: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.790000] spi_interrupt_handler
[   72.800000] rx: bdbacfe1: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.810000] esp_spi_work
[   72.810000] tx: 5851f78a: 03 00 00 00 1a 00 0c 00 52 01 00 00 01 00 13 00  ........R.......
[   72.820000] tx: 474dfc0f: 12 00 00 00 03 01 05 02 01 01 00 01 e8 01 07 01  ................
[   72.840000] rx: c1826a20: 02 00 00 00 0d 00 0c 00 37 05 00 00 04 0e 0a 05  ........7.......
[   72.840000] spi_interrupt_handler
[   72.850000] rx: af41730f: 09 10 00 ca 80 f6 bd f5 f0 e1 c9 3f 9d 05 00 00  ...........?....
[   72.860000] esp32_spi: process_rx_packet: Checksum enabled
[   72.870000] bt_tx: af99e9ac: 02 10 00                                         ...
[   72.880000] esp_spi_work
[   72.880000] tx: 57628127: 02 00 00 00 03 00 0d 00 25 00 00 00 01 02 10 00  ........%.......
[   72.890000] tx: a6fe7cc2: 39 37 38 36 66 37 3a 20 30 31 20 31 30 20 30 30  9786f7: 01 10 00
[   72.910000] rx: f30900b4: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.920000] rx: f6f072dd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.940000] spi_data_ready_interrupt_handler
[   72.940000] esp_spi_work
[   72.950000] tx: 4b95489a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.950000] tx: c9833840: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.980000] rx: 3a467120: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   72.980000] spi_interrupt_handler
[   72.990000] rx: 72c4ed44: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.000000] esp_spi_work
[   73.000000] tx: 2d3bfbe4: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.010000] tx: e02d216a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.030000] rx: 02a8af3b: 02 00 00 00 47 00 0c 00 ea 0c 00 00 04 0e 44 05  ....G.........D.
[   73.030000] spi_interrupt_handler
[   73.050000] rx: a37f9291: 02 10 00 20 00 80 00 00 c0 00 00 00 00 e4 00 00  ... ............
[   73.060000] esp32_spi: process_rx_packet: Checksum enabled
[   73.060000] bt_tx: a19bfbd0: 03 20 00                                         . .
[   73.070000] esp_spi_work
[   73.070000] tx: 18ef6dad: 02 00 00 00 03 00 0d 00 36 00 00 00 01 03 20 00  ........6..... .
[   73.080000] tx: bea5d07e: 34 31 37 33 30 66 3a 20 30 39 20 31 30 20 30 30  41730f: 09 10 00
[   73.110000] rx: 6604a48f: ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.110000] rx: 24947527: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.130000] spi_data_ready_interrupt_handler
[   73.140000] esp_spi_work
[   73.140000] tx: b8f60a2f: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.150000] tx: 01a871f6: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.170000] rx: 9e6fd751: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.170000] spi_interrupt_handler
[   73.180000] rx: 0ebd40b0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.190000] esp_spi_work
[   73.200000] tx: d1a53b02: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.210000] tx: 9e26ac49: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.230000] rx: d5632fa1: 02 00 00 00 0f 00 0c 00 64 02 00 00 04 0e 0c 05  ........d.......
[   73.230000] spi_interrupt_handler
[   73.240000] rx: 0465e5e3: 03 20 00 ff f9 01 08 00 00 00 00 3f 9d 05 00 00  . .........?....
[   73.250000] esp32_spi: process_rx_packet: Checksum enabled
[   73.260000] bt_tx: 1dbd6160: 02 20 00                                         . .
[   73.260000] esp_spi_work
[   73.270000] tx: fecb74f5: 02 00 00 00 03 00 0d 00 35 00 00 00 01 02 20 00  ........5..... .
[   73.280000] tx: 27205947: 37 66 39 32 39 31 3a 20 30 32 20 31 30 20 30 30  7f9291: 02 10 00
[   73.300000] rx: 6f9a270a: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.300000] spi_interrupt_handler
[   73.310000] rx: 4be00fe8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.320000] esp_spi_work
[   73.330000] spi_data_ready_interrupt_handler
[   73.330000] esp_spi_work
[   73.330000] tx: ba9f75b9: 3d 45 69 7c f7 d6 cc 35 3c 79 97 a5 bc 91 46 c3  =Ei|...5<y....F.
[   73.340000] tx: cb94497a: 4b 67 7b 37 ad a9 2a 6b 24 b3 6d 57 8f 58 b0 ef  Kg{7..*k$.mW.X..
[   73.370000] rx: ff3c98c0: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.370000] spi_interrupt_handler
[   73.380000] rx: 29f31c58: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.390000] esp_spi_work
[   73.390000] tx: fd4a6ed2: 25 be 19 f8 db 3c ce f9 68 b4 1b e8 1d 67 ef ba  %....<..h....g..
[   73.400000] tx: b23c7a16: 2e c1 8b 66 3c 2d f2 e9 07 ca dc 63 3f e7 59 73  ...f<-.....c?.Ys
[   73.420000] rx: 9e1cdc16: 02 00 00 00 0a 00 0c 00 5f 01 00 00 04 0e 07 05  ........_.......
[   73.420000] spi_interrupt_handler
[   73.430000] rx: b2cec522: 02 20 00 fb 00 0c c9 3f 54 00 00 00 e8 eb c9 3f  . .....?T......?
[   73.440000] esp32_spi: process_rx_packet: Checksum enabled
[   73.450000] bt_tx: 6652fba1: 1c 20 00                                         . .
[   73.460000] esp_spi_work
[   73.460000] tx: 2217769a: 02 00 00 00 03 00 0d 00 4f 00 00 00 01 1c 20 00  ........O..... .
[   73.470000] tx: 29ac3465: 36 35 65 35 65 33 3a 20 30 33 20 32 30 20 30 30  65e5e3: 03 20 00
[   73.490000] rx: 4b2b1a28: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.500000] spi_interrupt_handler
[   73.510000] rx: e3d497fd: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.520000] esp_spi_work
[   73.520000] spi_data_ready_interrupt_handler
[   73.520000] esp_spi_work
[   73.530000] tx: 5fc2a9a4: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.540000] tx: 4f754aeb: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.560000] rx: 9c69e883: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.560000] spi_interrupt_handler
[   73.570000] rx: 1946fb4d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.580000] esp_spi_work
[   73.580000] tx: 4de58cb7: 02 00 00 00 07 00 0c 00 00 00 00 00 04 0e 04 05  ................
[   73.590000] tx: a1f67b4c: 03 0c 00 3f c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  ...?...?T......?
[   73.620000] rx: 81125565: 02 00 00 00 0f 00 0c 00 7a 05 00 00 04 0e 0c 05  ........z.......
[   73.620000] rx: bf00e12b: 1c 20 00 ff ff ff ff ff 03 00 00 3f 9d 05 00 00  . .........?....
[   73.630000] esp32_spi: process_rx_packet: Checksum enabled
[   73.640000] bt_tx: a19bfbd0: 01 0c 08 90 e8 04 02 00 80 00 20                 ..........
[   73.650000] esp_spi_work
[   73.650000] tx: ad62be95: 02 00 00 00 0b 00 0d 00 4e 02 00 00 01 01 0c 08  ........N.......
[   73.660000] tx: a258b0b7: 90 e8 04 02 00 80 00 20 30 32 20 32 30 20 30 30  ....... 02 20 00
[   73.680000] rx: 3c088738: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.690000] spi_interrupt_handler
[   73.700000] rx: 8476fe3c: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.710000] esp_spi_work
[   73.710000] spi_data_ready_interrupt_handler
[   73.720000] esp_spi_work
[   73.720000] tx: 6bb6179c: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.730000] tx: 500fc2b8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.750000] rx: cdc73fd0: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.750000] spi_interrupt_handler
[   73.770000] rx: 828f4d90: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.780000] esp_spi_work
[   73.780000] tx: f6da712e: 02 00 00 00 0f 00 0c 00 00 00 00 00 04 0e 0c 05  ................
[   73.790000] tx: 5aab1e70: 03 10 00 00 00 00 00 60 00 00 00 3f 9d 05 00 00  .......`...?....
[   73.810000] rx: 141a316f: 02 00 00 00 07 00 0c 00 3d 00 00 00 04 0e 04 05  ........=.......
[   73.820000] rx: fb3e1b67: 01 0c 00 3f c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  ...?...?T......?
[   73.830000] esp32_spi: process_rx_packet: Checksum enabled
[   73.840000] bt_tx: 6652fba1: 01 20 08 ff 1f 0a 00 00 00 00 00                 . .........
[   73.840000] esp_spi_work
[   73.850000] tx: fecb74f5: 02 00 00 00 0b 00 0d 00 6c 01 00 00 01 01 20 08  ........l..... .
[   73.860000] tx: 27205947: ff 1f 0a 00 00 00 00 00 31 63 20 32 30 20 30 30  ........1c 20 00
[   73.880000] rx: d2913ea0: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.880000] spi_interrupt_handler
[   73.890000] rx: e12d8e2d: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.900000] esp_spi_work
[   73.910000] spi_data_ready_interrupt_handler
[   73.910000] esp_spi_work
[   73.910000] tx: 60f3c8a6: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.920000] tx: f6b3139a: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.950000] rx: e3062420: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   73.950000] rx: 0f8345e9: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   75.920000] Bluetooth: hci0: Opcode 0x2001 failed: -110
[   87.780000] esp32_spi: update_mac_filter: Port is not open yet, skipping mac filter update
[   87.810000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   87.880000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   88.410000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   88.960000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   88.960000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   89.130000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   92.440000] esp32_spi: esp_cfg80211_scan:
[   92.440000] esp32_spi: cmd_scan_request: Queueing CMD_SCAN_REQUEST
[   92.440000] esp32_spi: esp_cmd_work: Processing Command [0x4]
[   92.440000] esp_spi_work
[   92.440000] tx: 1f6ecccb: 00 00 01 00 34 00 0c 00 3f 06 00 00 04 00 00 00  ....4...?.......
[   92.450000] tx: a370bac4: 00 00 00 00 ff ff ff ff ff ff 00 00 00 00 00 00  ................
[   92.470000] rx: 46aa14fe: 02 00 00 00 07 00 0c 00 51 00 00 00 04 0e 04 05  ........Q.......
[   92.480000] spi_interrupt_handler
[   92.480000] rx: 1552be81: 01 20 00 3f c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  . .?...?T......?
[   92.490000] esp32_spi: process_rx_packet: Checksum enabled
[   92.500000] esp_spi_work
[   92.610000] spi_data_ready_interrupt_handler
[   92.610000] esp_spi_work
[   92.620000] tx: 22a3d061: e1 df f9 40 22 93 19 a0 2c ee d3 de 79 8e f6 76  ...@"...,...y..v
[   92.630000] tx: 8de5a6f3: 2f 15 ed 1d 63 b7 b7 51 b6 b7 5c b6 b7 4a b6 b7  /...c..Q..\..J..
[   92.640000] esp32_spi: esp_hard_start_xmit: Port not yet open
[   92.650000] rx: 108b1693: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   92.650000] spi_interrupt_handler
[   92.660000] rx: 94df8a13: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   92.670000] esp_spi_work
[   92.670000] tx: eb838c53: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   92.680000] tx: 3c0edc5b: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   92.710000] rx: d545c665: 00 00 03 00 62 01 0c 00 96 43 00 00 01 01 5e 01  ....b....C....^.
[   92.710000] spi_interrupt_handler
[   92.720000] rx: 51f3e464: 24 5a 4c 99 cd 0a 08 01 b5 ff ff ff 37 3d 68 01  $ZL.........7=h.
[   92.730000] esp32_spi: process_rx_packet: Checksum enabled
[   92.730000] esp_spi_work
[   92.740000] tx: abf96005: cd a0 76 9e 69 30 3a cf 1c 4d ff ad e5 38 ad 05  ..v.i0:..M...8..
[   92.740000] tx: 968da903: 8c 45 3f 99 e7 b3 26 06 0c 7a 65 a9 4f 9d 61 ba  .E?...&..ze.O.a.
[   92.770000] rx: 01d414f7: 00 00 03 00 69 01 0c 00 76 47 00 00 01 01 65 01  ....i...vG....e.
[   92.770000] spi_interrupt_handler
[   92.780000] rx: 1140e051: 26 5a 4c 99 cd 0a 08 01 b6 ff ff ff ad 55 68 01  &ZL..........Uh.
[   92.790000] esp32_spi: process_rx_packet: Checksum enabled
[   92.800000] esp_spi_work
[   92.800000] tx: 64f4c9ef: ce 6c 09 fb 14 1a ac ed 63 ba 2d bb 9f c3 59 5f  .l......c.-...Y_
[   92.810000] tx: e57948aa: 99 9f 28 92 7d 8a 60 3e 68 1e fe 24 cf 86 75 17  ..(.}.`>h..$..u.
[   92.830000] rx: e8bc7d12: 80 00 02 00 08 00 0c 00 1c 00 00 00 04 02 00 00  ................
[   92.840000] spi_interrupt_handler
[   92.840000] rx: e30994f3: 00 00 00 00 c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  .......?T......?
[   92.850000] esp32_spi: process_rx_packet: Checksum enabled
[   92.860000] esp32_spi: process_rx_packet: Checksum mismatch: 9c 1c
[   92.870000] esp_spi_work
[   93.290000] spi_data_ready_interrupt_handler
[   93.300000] esp_spi_work
[   93.300000] tx: c045ef6d: c0 40 00 0c 00 01 00 00 11 94 00 02 c2 21 c2 21  .@...........!.!
[   93.310000] tx: a978f561: 00 0c 00 01 00 00 11 94 00 02 c2 09 24 42 43 44  ............$BCD
[   93.330000] rx: b49379b6: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   93.330000] spi_interrupt_handler
[   93.340000] rx: ef7f42b7: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   93.350000] esp_spi_work
[   93.360000] tx: 798f1bee: 3d 84 df b6 38 e4 fe 2c ac a1 e4 a7 73 99 fe 76  =...8..,....s..v
[   93.370000] tx: 932cf52c: 39 6a fb 76 58 58 f7 39 24 ff fd b4 2f 53 7b f7  9j.vXX.9$.../S{.
[   93.390000] rx: b8d3c99a: 00 00 03 00 30 01 0c 00 a9 31 00 00 01 01 2c 01  ....0....1....,.
[   93.390000] spi_interrupt_handler
[   93.400000] rx: c9827e85: 5c fa 25 54 11 b0 08 06 a8 ff ff ff 07 db 72 01  \.%T..........r.
[   93.410000] esp32_spi: process_rx_packet: Checksum enabled
[   93.420000] esp_spi_work
[   93.850000] spi_data_ready_interrupt_handler
[   93.850000] esp_spi_work
[   93.850000] tx: 771a329e: 06 e9 6f fe 51 1d d6 c9 98 73 f7 88 c4 50 3d ea  ..o.Q....s...P=.
[   93.860000] tx: 741d21f4: ca e2 78 b0 9a 44 56 0f 74 ff 9a 84 b7 a7 33 4b  ..x..DV.t.....3K
[   93.890000] rx: c51bf3dc: 7f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   93.890000] spi_interrupt_handler
[   93.900000] rx: 42ad87d3: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   93.910000] esp_spi_work
[   93.910000] tx: 0afd5934: 8f 07 18 f6 4a c3 5e 7f b8 de 7a e2 08 3c 78 7d  ....J.^...z..<x}
[   93.920000] tx: eda5f9e2: 5f 84 b1 0b 0e dd 02 ac a8 6e c4 90 ef 6b 5c 28  _........n...k\(
[   93.940000] rx: c7ba494b: 00 00 03 00 57 01 0c 00 11 47 00 00 01 01 53 01  ....W....G....S.
[   93.940000] spi_interrupt_handler
[   93.950000] rx: 62df04d0: 26 5a 4c 99 d2 4a 05 0b c1 ff ff ff df 4b 7b 01  &ZL..J.......K{.
[   93.960000] esp32_spi: process_rx_packet: Checksum enabled
[   93.970000] esp_spi_work
[   93.970000] tx: 464f229d: 26 33 ee a2 b7 75 ef a5 fd a2 56 c4 50 69 1f b0  &3...u....V.Pi..
[   93.980000] tx: d602c6ed: 73 03 c7 ff 5b 9c bb bf 7f 45 f0 83 3f bd 22 f4  s...[....E..?.".
[   94.010000] rx: aace2f13: 00 00 03 00 50 01 0c 00 91 43 00 00 01 01 4c 01  ....P....C....L.
[   94.010000] spi_interrupt_handler
[   94.020000] rx: 2b289c42: 24 5a 4c 99 d2 4a 05 0b c0 ff ff ff 94 64 7b 01  $ZL..J.......d{.
[   94.030000] esp32_spi: process_rx_packet: Checksum enabled
[   94.030000] esp_spi_work
[   94.040000] tx: 5b84d42d: fd 11 36 86 12 ff 5a 9a 07 95 7f 5f 14 4a 14 ff  ..6...Z...._.J..
[   94.040000] tx: e80af0a6: 9b e5 b1 36 cb ab e5 44 6a 07 35 a2 dd f2 ec 57  ...6...Dj.5....W
[   94.070000] rx: de90bf12: 80 00 03 00 a2 00 0c 00 70 1b 00 00 01 01 9e 00  ........p.......
[   94.070000] spi_interrupt_handler
[   94.080000] rx: 6c809ac8: 60 dc 81 da de 1d 08 0b a8 ff ff ff 2f c0 7b 01  `.........../.{.
[   94.090000] esp32_spi: process_rx_packet: Checksum enabled
[   94.100000] esp32_spi: process_rx_packet: Checksum mismatch: 1bf0 1b70
[   94.100000] esp_spi_work
[   94.350000] spi_data_ready_interrupt_handler
[   94.350000] esp_spi_work
[   94.350000] tx: ec43691f: be da a7 fc d1 13 59 46 ca e1 1a 68 7d b2 06 b5  ......YF...h}...
[   94.360000] tx: ea10f6e1: f3 a2 a4 9f 1b 73 c0 57 47 db b1 10 7e 4d f2 3c  .....s.WG...~M.<
[   94.390000] rx: e40fd905: ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   94.390000] spi_interrupt_handler
[   94.400000] rx: 2f6a0316: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
[   94.410000] esp_spi_work
[   94.410000] tx: 1b02e282: cc ee 8a 48 8b 05 79 f0 df 3d 90 8d fa c6 3b cd  ...H..y..=....;.
[   94.420000] tx: 569cf806: 44 bc 92 c3 90 18 69 c3 98 01 fd de 51 4c bf 01  D.....i.....QL..
[   94.440000] rx: da443328: 00 00 03 00 04 00 0c 00 14 00 00 00 01 00 00 00  ................
[   94.440000] spi_interrupt_handler
[   94.450000] rx: 44e6a982: 00 00 00 00 c4 eb c9 3f 54 00 00 00 e8 eb c9 3f  .......?T......?
[   94.460000] esp32_spi: process_rx_packet: Checksum enabled
[   94.470000] esp_spi_work
[   97.520000] esp32_spi: wait_and_decode_cmd_resp: Command[0x4] timed out
[   97.520000] esp32_spi: cmd_scan_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -22
[  100.320000] esp32_spi: esp_hard_start_xmit: Port not yet open
#

ESP32 :

ESP-ROM:esp32c3-api1-20210207
Build:Feb  7 2021
rst:0x1 (POWERON),boot:0xa (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x170c
load:0x403cc710,len:0x968
load:0x403ce710,len:0x2f9c
entry 0x403cc710
I (30) boot: ESP-IDF v5.1.3-dirty 2nd stage bootloader
I (30) boot: compile time Jun 29 2024 16:31:41
I (30) boot: chip revision: v0.4
I (34) boot.esp32c3: SPI Speed      : 80MHz
I (39) boot.esp32c3: SPI Mode       : DIO
I (43) boot.esp32c3: SPI Flash Size : 4MB
I (48) boot: Enabling RNG early entropy source...
I (53) boot: Partition Table:
I (57) boot: ## Label            Usage          Type ST Offset   Length
I (64) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (72) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (79) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (87) boot:  3 factory          factory app      00 00 00010000 00100000
I (94) boot:  4 ota_0            OTA app          00 10 00110000 00100000
I (102) boot:  5 ota_1            OTA app          00 11 00210000 00100000
I (109) boot: End of partition table
I (113) boot: Defaulting to factory image
I (118) esp_image: segment 0: paddr=00010020 vaddr=3c090020 size=223b0h (140208) map
I (149) esp_image: segment 1: paddr=000323d8 vaddr=3fc94800 size=03340h ( 13120) load
I (152) esp_image: segment 2: paddr=00035720 vaddr=40380000 size=0a8f8h ( 43256) load
I (163) esp_image: segment 3: paddr=00040020 vaddr=42000020 size=8cb18h (576280) map
I (257) esp_image: segment 4: paddr=000ccb40 vaddr=4038a8f8 size=09d60h ( 40288) load
I (271) boot: Loaded app from partition at offset 0x10000
I (272) boot: Disabling RNG early entropy source...
I (283) cpu_start: Unicore app
I (283) cpu_start: Pro cpu up.
D (292) clk: RTC_SLOW_CLK calibration value: 3608973
I (297) cpu_start: Pro cpu start user code
I (297) cpu_start: cpu freq: 160000000 Hz
I (297) cpu_start: Application information:
I (300) cpu_start: Project name:     network_adapter
I (306) cpu_start: App version:      release/ng-v1.0.2-268-g99efd10-
I (313) cpu_start: Compile time:     Jun 29 2024 16:31:37
I (319) cpu_start: ELF file SHA256:  a37172c0f6344230...
I (325) cpu_start: ESP-IDF:          v5.1.3-dirty
I (330) cpu_start: Min chip rev:     v0.3
I (335) cpu_start: Max chip rev:     v1.99
I (340) cpu_start: Chip rev:         v0.4
D (344) memory_layout: Checking 5 reserved memory ranges:
D (350) memory_layout: Reserved memory range 0x3fc80000 - 0x3fc94800
D (356) memory_layout: Reserved memory range 0x3fc94800 - 0x3fc9bbb0
D (363) memory_layout: Reserved memory range 0x3fcdf060 - 0x3fce0000
D (369) memory_layout: Reserved memory range 0x50000000 - 0x50000010
D (375) memory_layout: Reserved memory range 0x50001fe8 - 0x50002000
D (382) memory_layout: Building list of available memory regions:
D (388) memory_layout: Available memory region 0x3fc9bbb0 - 0x3fca0000
D (395) memory_layout: Available memory region 0x3fca0000 - 0x3fcc0000
D (401) memory_layout: Available memory region 0x3fcc0000 - 0x3fcdc710
D (408) memory_layout: Available memory region 0x3fcdc710 - 0x3fcdf060
D (414) memory_layout: Available memory region 0x50000010 - 0x50001fe8
I (421) heap_init: Initializing. RAM available for dynamic allocation:
D (428) heap_init: New heap initialised at 0x3fc9bbb0
I (433) heap_init: At 3FC9BBB0 len 00024450 (145 KiB): DRAM
D (440) heap_init: New heap initialised at 0x3fcc0000
I (445) heap_init: At 3FCC0000 len 0001C710 (113 KiB): DRAM/RETENTION
I (452) heap_init: At 3FCDC710 len 00002950 (10 KiB): DRAM/RETENTION/STACK
D (459) heap_init: New heap initialised at 0x50000010
I (465) heap_init: At 50000010 len 00001FD8 (7 KiB): RTCRAM
D (471) intr_alloc: Connected src 27 to int 2 (cpu 0)
D (476) spi_flash: trying chip: issi
D (480) spi_flash: trying chip: gd
D (483) spi_flash: trying chip: mxic
D (487) spi_flash: trying chip: winbond
D (491) spi_flash: trying chip: boya
D (494) spi_flash: trying chip: th
D (498) spi_flash: trying chip: generic
I (502) spi_flash: detected chip: generic
I (506) spi_flash: flash io: dio
D (510) cpu_start: calling init function: 0x42074f2c
D (515) cpu_start: calling init function: 0x42074e84
D (520) cpu_start: calling init function: 0x42074c94
D (525) cpu_start: calling init function: 0x420747d2
D (530) cpu_start: calling init function: 0x4205c474
D (536) efuse: In EFUSE_BLK2__DATA4_REG is used 2 bits starting with 0 bit
D (542) efuse: In EFUSE_BLK2__DATA5_REG is used 10 bits starting with 18 bit
D (549) cpu_start: calling init function: 0x4202c17e
D (555) cpu_start: calling init function: 0x4200002a
D (560) cpu_start: calling init function: 0x42005a1e on core: 0
D (566) intr_alloc: Connected src 39 to int 3 (cpu 0)
D (571) cpu_start: calling init function: 0x420032da on core: 0
I (577) sleep: Configure to isolate all GPIO pins in sleep state
I (583) sleep: Enable automatic switching of GPIO sleep configuration
D (590) cpu_start: calling init function: 0x42001376 on core: 0
I (597) coexist: coex firmware version: 77cd7f8
I (602) coexist: coexist rom version 9387209
D (607) intr_alloc: Connected src 50 to int 4 (cpu 0)
I (612) app_start: Starting scheduler on CPU0
D (617) intr_alloc: Connected src 37 to int 7 (cpu 0)
I (617) main_task: Started on CPU0
D (621) heap_init: New heap initialised at 0x3fcdc710
D (626) intr_alloc: Connected src 33 to int 9 (cpu 0)
I (631) main_task: Calling app_main()
I (635) stats: *********************************************************************
I (644) stats:                 ESP-Hosted Firmware version :: 1.0.3
I (653) stats:                 Transport used :: SPI only
I (661) stats: *********************************************************************
I (669) FW_MAIN: Supported features are:
I (674) FW_MAIN: - WLAN over SPI
I (678) FW_BT: - BT/BLE
I (681) FW_BT:    - HCI Over SPI
I (685) FW_BT:    - BLE only
I (688) FW_MAIN: Capabilities: 0xe8
pp rom version: 9387209
net80211 rom version: 9387209
I (699) wifi:wifi driver task: 3fca2050, prio:23, stack:6656, core=0
D (704) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 8 bit
D (710) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 0 bit
D (717) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 24 bit
D (724) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 16 bit
D (731) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 8 bit
D (738) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 0 bit
I (746) wifi:wifi firmware version: e3cf69a
I (749) wifi:wifi certification version: v7.0
I (753) wifi:config NVS flash: disabled
I (757) wifi:config nano formating: disabled
I (761) wifi:Init data frame dynamic rx buffer num: 40
I (766) wifi:Init static rx mgmt buffer num: 5
I (770) wifi:Init management short buffer num: 32
I (774) wifi:Init dynamic tx buffer num: 40
I (778) wifi:Init static tx FG buffer num: 2
I (782) wifi:Init static rx buffer size: 1600
I (786) wifi:Init static rx buffer num: 20
I (790) wifi:Init dynamic rx buffer num: 40
I (794) wifi_init: rx ba win: 32
I (798) wifi_init: tcpip mbox: 32
I (802) wifi_init: udp mbox: 6
I (806) wifi_init: tcp mbox: 6
I (809) wifi_init: tcp tx win: 5760
I (813) wifi_init: tcp rx win: 5760
I (818) wifi_init: tcp mss: 1440
I (821) wifi_init: WiFi IRAM OP enabled
I (826) wifi_init: WiFi RX IRAM OP enabled
I (831) BLE_INIT: BT controller compile version [9359a4d]
I (838) BLE_INIT: Bluetooth MAC: f0:f5:bd:f6:80:ca

I (843) phy_init: phy_version 1150,7c3c08f,Jan 24 2024,17:32:21
D (849) phy_init: loading PHY init data from application binary
D (855) nvs: nvs_open_from_partition phy 0
D (859) nvs: nvs_get cal_version 4
D (863) nvs: nvs_get_str_or_blob cal_mac
D (867) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 8 bit
D (874) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 0 bit
D (880) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 24 bit
D (887) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 16 bit
D (895) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 8 bit
D (901) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 0 bit
D (908) nvs: nvs_get_str_or_blob cal_data
D (914) nvs: nvs_close 1
D (915) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 8 bit
D (922) efuse: In EFUSE_BLK1__DATA1_REG is used 8 bits starting with 0 bit
D (929) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 24 bit
D (936) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 16 bit
D (943) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 8 bit
D (950) efuse: In EFUSE_BLK1__DATA0_REG is used 8 bits starting with 0 bit
D (971) temperature_sensor: range changed, change to index 2
I (988) FW_MAIN: ESP Bluetooth MAC addr: f0-f5-bd-f6-80-ca

I (988) FW_SPI: Using SPI interface
I (988) gpio: GPIO[3]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
I (997) gpio: GPIO[4]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
SPI Slave mode : 1
D (1008) gdma: new group (0) at 0x3fcb4124
D (1012) gdma: new pair (0,0) at 0x3fcb4158
D (1016) gdma: new tx channel (0,0) at 0x3fcb40f0
D (1021) gdma: new rx channel (0,0) at 0x3fcb4178
D (1026) spi: SPI2 use iomux pins.
D (1029) intr_alloc: Connected src 19 to int 10 (cpu 0)
I (2035) FW_MAIN: Initial set up done
I (2035) main_task: Returned from app_main()
I (2064) FW_SPI: 0x3fcb8ac0   03 00 00 00 1a 00 0c 00  52 01 00 00 01 00 13 00  |........R.......|
I (2065) FW_SPI: 0x3fcb8ad0   12 00 00 00 03 01 05 02  01 01 00 01 e8 01 07 01  |................|
I (2209) FW_SPI: 0x3fc9db3c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2209) FW_SPI: 0x3fc9db4c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2218) SDIO Rx: 0x3fc9e18c   01 00 00 00 00 00 00 00                           |........|
I (2226) Rx Cmd: 0x3fc9e18c   01 00 00 00 00 00 00 00                           |........|
I (2235) FW_MAIN: INIT Interface command

D (2240) event: created task for loop 0x3fca0620
D (2245) event: created event loop 0x3fca0620
D (2250) wifi:filter: set rx policy=0
I (2253) wifi:mode : sta (f0:f5:bd:f6:80:c8)
I (2256) wifi:enable tsf
D (2259) wifi:filter: set rx policy=1
D (2262) wifi:connect status 0 -> 0
D (2265) event: running task for loop 0x3fca0620
D (2270) event: running post WIFI_EVENT:2 with handler 0x4200a27a and context 0x3fca21d4 on loop 0x3fca0620
I (2280) FW_CMD: Wifi Sta mode set

I (2305) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2305) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2360) FW_SPI: 0x3fc9ebcc   00 00 02 00 08 00 0c 00  19 00 00 00 01 02 00 00  |................|
I (2361) FW_SPI: 0x3fc9ebdc   00 00 00 00 c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |.......?T......?|
I (2424) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2425) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2434) SDIO Rx: 0x3fcb9110   03 00 00 00 00 00 00 00                           |........|
I (2442) Rx Cmd: 0x3fcb9110   03 00 00 00 00 00 00 00                           |........|
I (2451) FW_MAIN: Get MAC command

I (2455) FW_CMD: 0x3fcb7740   f0 f5 bd f6 80 c8                                 |......|
I (2503) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2504) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2560) FW_SPI: 0x3fc9e204   00 00 02 00 10 00 0c 00  09 05 00 00 03 02 06 00  |................|
I (2560) FW_SPI: 0x3fc9e214   00 00 00 00 f0 f5 bd f6  80 c8 00 00 9d 05 00 00  |................|
I (2642) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2642) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2651) SDIO Rx: 0x3fcba258   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (2660) Rx Cmd: 0x3fcba258   0f 00 00 00 00 00 00 00  00 00 00 00              |............|
I (2669) FW_MAIN: Tx power command

I (2673) MAC Filter: 0x3fc9869c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2683) MAC Filter: 0x3fc986ac   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2693) MAC Filter: 0x3fc986bc   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2703) MAC Filter: 0x3fc986cc   00                                                |.|
I (2752) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2752) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (2809) FW_SPI: 0x3fc9ebcc   00 00 02 00 0c 00 0c 00  7b 00 00 00 0f 02 00 00  |........{.......|
I (2809) FW_SPI: 0x3fc9ebdc   00 00 00 00 50 00 00 00  54 00 00 00 e8 eb c9 3f  |....P...T......?|
I (3143) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3143) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3152) SDIO Rx: 0x3fcb9111   03 0c 00                                          |...|
I (3160) H->S BT: 0x3fcb9111   03 0c 00                                          |...|
I (3210) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3211) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3266) FW_SPI: 0x3fc9ebcc   02 00 00 00 07 00 0c 00  3f 00 00 00 04 0e 04 05  |........?.......|
I (3267) FW_SPI: 0x3fc9ebdc   03 0c 00 3f c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |...?...?T......?|
I (3338) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3338) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3347) SDIO Rx: 0x3fcba259   03 10 00                                          |...|
I (3355) H->S BT: 0x3fcba259   03 10 00                                          |...|
I (3404) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3404) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3460) FW_SPI: 0x3fc9e204   02 00 00 00 0f 00 0c 00  b3 00 00 00 04 0e 0c 05  |................|
I (3461) FW_SPI: 0x3fc9e214   03 10 00 00 00 00 00 60  00 00 00 3f 9d 05 00 00  |.......`...?....|
I (3529) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3529) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3538) SDIO Rx: 0x3fcb9111   01 10 00                                          |...|
I (3546) H->S BT: 0x3fcb9111   01 10 00                                          |...|
I (3596) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3596) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3652) FW_SPI: 0x3fc9e204   02 00 00 00 0f 00 0c 00  76 01 00 00 04 0e 0c 05  |........v.......|
I (3653) FW_SPI: 0x3fc9e214   01 10 00 09 16 00 09 e5  02 16 00 3f 9d 05 00 00  |...........?....|
I (3724) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3724) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3733) SDIO Rx: 0x3fcba259   09 10 00                                          |...|
I (3741) H->S BT: 0x3fcba259   09 10 00                                          |...|
I (3791) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3791) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3847) FW_SPI: 0x3fc9e204   02 00 00 00 0d 00 0c 00  37 05 00 00 04 0e 0a 05  |........7.......|
I (3848) FW_SPI: 0x3fc9e214   09 10 00 ca 80 f6 bd f5  f0 e1 c9 3f 9d 05 00 00  |...........?....|
I (3919) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3919) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3928) SDIO Rx: 0x3fcb9111   02 10 00                                          |...|
I (3936) H->S BT: 0x3fcb9111   02 10 00                                          |...|
I (3984) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (3985) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4042) FW_SPI: 0x3fc9e250   02 00 00 00 47 00 0c 00  ea 0c 00 00 04 0e 44 05  |....G.........D.|
I (4042) FW_SPI: 0x3fc9e260   02 10 00 20 00 80 00 00  c0 00 00 00 00 e4 00 00  |... ............|
I (4113) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4114) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4123) SDIO Rx: 0x3fcba259   03 20 00                                          |. .|
I (4130) H->S BT: 0x3fcba259   03 20 00                                          |. .|
I (4179) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4180) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4236) FW_SPI: 0x3fc9e204   02 00 00 00 0f 00 0c 00  64 02 00 00 04 0e 0c 05  |........d.......|
I (4237) FW_SPI: 0x3fc9e214   03 20 00 ff f9 01 08 00  00 00 00 3f 9d 05 00 00  |. .........?....|
I (4307) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4307) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4316) SDIO Rx: 0x3fcb9111   02 20 00                                          |. .|
I (4324) H->S BT: 0x3fcb9111   02 20 00                                          |. .|
I (4372) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4373) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4429) FW_SPI: 0x3fc9ebcc   02 00 00 00 0a 00 0c 00  5f 01 00 00 04 0e 07 05  |........_.......|
I (4429) FW_SPI: 0x3fc9ebdc   02 20 00 fb 00 0c c9 3f  54 00 00 00 e8 eb c9 3f  |. .....?T......?|
I (4500) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4501) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4510) SDIO Rx: 0x3fcba259   1c 20 00                                          |. .|
I (4517) H->S BT: 0x3fcba259   1c 20 00                                          |. .|
I (4567) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4567) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4623) FW_SPI: 0x3fc9e204   02 00 00 00 0f 00 0c 00  7a 05 00 00 04 0e 0c 05  |........z.......|
I (4624) FW_SPI: 0x3fc9e214   1c 20 00 ff ff ff ff ff  03 00 00 3f 9d 05 00 00  |. .........?....|
I (4692) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4692) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4701) SDIO Rx: 0x3fcb9111   01 0c 08 90 e8 04 02 00  80 00 20                 |.......... |
I (4710) H->S BT: 0x3fcb9111   01 0c 08 90 e8 04 02 00  80 00 20                 |.......... |
I (4761) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4761) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4817) FW_SPI: 0x3fc9ebcc   02 00 00 00 07 00 0c 00  3d 00 00 00 04 0e 04 05  |........=.......|
I (4818) FW_SPI: 0x3fc9ebdc   01 0c 00 3f c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |...?...?T......?|
I (4886) FW_SPI: 0x3fcb9104   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4887) FW_SPI: 0x3fcb9114   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4896) SDIO Rx: 0x3fcba259   01 20 08 ff 1f 0a 00 00  00 00 00                 |. .........|
I (4904) H->S BT: 0x3fcba259   01 20 08 ff 1f 0a 00 00  00 00 00                 |. .........|
I (4953) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (4954) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23479) FW_SPI: 0x3fc9ebcc   02 00 00 00 07 00 0c 00  51 00 00 00 04 0e 04 05  |........Q.......|
I (23479) FW_SPI: 0x3fc9ebdc   01 20 00 3f c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |. .?...?T......?|
I (23488) SDIO Rx: 0x3fcba258   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (23497) SDIO Rx: 0x3fcba268   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23507) SDIO Rx: 0x3fcba278   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23517) SDIO Rx: 0x3fcba288   00 00 00 00                                       |....|
I (23526) Rx Cmd: 0x3fcba258   04 00 00 00 00 00 00 00  ff ff ff ff ff ff 00 00  |................|
I (23535) Rx Cmd: 0x3fcba268   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23545) Rx Cmd: 0x3fcba278   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23555) Rx Cmd: 0x3fcba288   00 00 00 00                                       |....|
I (23563) FW_MAIN: Scan request

D (23568) wifi:Start wifi scan
D (23570) wifi:sta_scan: default parameters, hidden=0, type=active, dur=<0,120>
D (23577) wifi:first chan=1
D (23580) wifi:filter: set rx policy=3
D (23583) wifi:clear scan ap list
D (23586) wifi:start scan: type=0x10f, priority=3, cb=0x0, arg=0x3fc990cc, ss_state=0x1, time=23592031, index=0
D (23596) wifi:perform scan: ss_state=0x9, chan<1,0>, dur<0,120>
I (23603) FW_CMD: handle_wpa_sta_rx_mgmt:653 beacon frames ignored

I (23609) FW_CMD: handle_wpa_sta_rx_mgmt:653 beacon frames ignored

I (23654) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23655) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (23712) FW_SPI: 0x3fcba890   00 00 03 00 62 01 0c 00  96 43 00 00 01 01 5e 01  |....b....C....^.|
I (23712) FW_SPI: 0x3fcba8a0   24 5a 4c 99 cd 0a 08 01  b5 ff ff ff 37 3d 68 01  |$ZL.........7=h.|
D (23722) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (23726) wifi:perform scan: ss_state=0x9, chan<2,0>, dur<0,120>
I (23774) FW_SPI: 0x3fcbaa04   00 00 03 00 69 01 0c 00  76 47 00 00 01 01 65 01  |....i...vG....e.|
I (23775) FW_SPI: 0x3fcbaa14   26 5a 4c 99 cd 0a 08 01  b6 ff ff ff ad 55 68 01  |&ZL..........Uh.|
I (23838) FW_SPI: 0x3fc9ebcc   00 00 02 00 08 00 0c 00  1c 00 00 00 04 02 00 00  |................|
I (23838) FW_SPI: 0x3fc9ebdc   00 00 00 00 c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |.......?T......?|
D (23852) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (23853) wifi:perform scan: ss_state=0x9, chan<3,0>, dur<0,120>
D (23978) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (23979) wifi:perform scan: ss_state=0x9, chan<4,0>, dur<0,120>
D (24100) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24100) wifi:perform scan: ss_state=0x9, chan<5,0>, dur<0,120>
D (24222) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24222) wifi:perform scan: ss_state=0x9, chan<6,0>, dur<0,120>
I (24299) FW_CMD: handle_wpa_sta_rx_mgmt:653 beacon frames ignored

I (24340) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (24340) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
D (24344) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24353) wifi:perform scan: ss_state=0x9, chan<7,0>, dur<0,120>
I (24394) FW_SPI: 0x3fc9e50c   00 00 03 00 30 01 0c 00  a9 31 00 00 01 01 2c 01  |....0....1....,.|
I (24395) FW_SPI: 0x3fc9e51c   5c fa 25 54 11 b0 08 06  a8 ff ff ff 07 db 72 01  |\.%T..........r.|
D (24480) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24480) wifi:perform scan: ss_state=0x9, chan<8,0>, dur<0,120>
D (24602) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24602) wifi:perform scan: ss_state=0x9, chan<9,0>, dur<0,120>
D (24723) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24724) wifi:perform scan: ss_state=0x9, chan<10,0>, dur<0,120>
D (24845) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24846) wifi:perform scan: ss_state=0x9, chan<11,0>, dur<0,120>
I (24852) FW_CMD: handle_wpa_sta_rx_mgmt:658 probe response

I (24858) FW_CMD: handle_wpa_sta_rx_mgmt:658 probe response

I (24882) FW_CMD: handle_wpa_sta_rx_mgmt:653 beacon frames ignored

I (24893) FW_SPI: 0x3fcb8ac0   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (24893) FW_SPI: 0x3fcb8ad0   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (24949) FW_SPI: 0x3fcb9104   00 00 03 00 57 01 0c 00  11 47 00 00 01 01 53 01  |....W....G....S.|
I (24949) FW_SPI: 0x3fcb9114   26 5a 4c 99 d2 4a 05 0b  c1 ff ff ff df 4b 7b 01  |&ZL..J.......K{.|
D (24967) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (24968) wifi:perform scan: ss_state=0x9, chan<12,0>, dur<360,360>
I (25012) FW_SPI: 0x3fc9e660   00 00 03 00 50 01 0c 00  91 43 00 00 01 01 4c 01  |....P....C....L.|
I (25013) FW_SPI: 0x3fc9e670   24 5a 4c 99 d2 4a 05 0b  c0 ff ff ff 94 64 7b 01  |$ZL..J.......d{.|
I (25074) FW_SPI: 0x3fcb926c   00 00 03 00 a2 00 0c 00  70 1b 00 00 01 01 9e 00  |........p.......|
I (25075) FW_SPI: 0x3fcb927c   60 dc 81 da de 1d 08 0b  a8 ff ff ff 2f c0 7b 01  |`.........../.{.|
D (25089) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (25090) wifi:perform scan: ss_state=0x9, chan<13,0>, dur<360,360>
D (25214) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (25215) wifi:perform scan: ss_state=0x9, chan<14,0>, dur<360,360>
D (25336) wifi:scan end: arg=0x0, status=0, ss_state=0x3
D (25337) wifi:filter: set rx policy=4
D (25337) wifi:first chan=1
D (25337) wifi:Send scan done event: status=0, apnum=6
D (25341) event: running post WIFI_EVENT:1 with handler 0x4200a27a and context 0x3fca21d4 on loop 0x3fca0620
I (25392) FW_SPI: 0x3fcba24c   ff 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (25392) FW_SPI: 0x3fcba25c   00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  |................|
I (25449) FW_SPI: 0x3fc9ebcc   00 00 03 00 04 00 0c 00  14 00 00 00 01 00 00 00  |................|
I (25449) FW_SPI: 0x3fc9ebdc   00 00 00 00 c4 eb c9 3f  54 00 00 00 e8 eb c9 3f  |.......?T......?|
naguirre commented 3 days ago

My bad the checksum error is there :

[   94.090000] esp32_spi: process_rx_packet: Checksum enabled
[   94.100000] esp32_spi: process_rx_packet: Checksum mismatch: 1bf0 1b70
mantriyogesh commented 3 days ago

Can you please add below code (and needed code) from FG to set HS down on CS assertion: https://github.com/espressif/esp-hosted/blob/8db84e42e71823e6a04ed55eef06d3ccacd8e4f6/esp_hosted_fg/esp/esp_driver/network_adapter/main/spi_slave_api.c#L636

naguirre commented 3 days ago

Hum, it looks much more stable with this! Scanning wifi passes every time!

naguirre commented 3 days ago

I removed all the logs, set the CLK back to 30Mhz, and configured SPI to MODE_2 but added register_hs_disable_pin as suggested I also rebased on top of master.

Raw TP mode. give me these results :

*** Raw Throughput testing: ESP --> Host started ***

I (3452) stats: 0-1 sec       10844.50 kbits/sec
I (4452) stats: 1-2 sec       12155.50 kbits/sec
I (5452) stats: 2-3 sec       11994.50 kbits/sec
I (6452) stats: 3-4 sec       11914.00 kbits/sec
I (7452) stats: 4-5 sec       12006.00 kbits/sec
I (8452) stats: 5-6 sec       12017.50 kbits/sec
I (9452) stats: 6-7 sec       11431.00 kbits/sec
I (10452) stats: 7-8 sec       12029.00 kbits/sec
I (11452) stats: 8-9 sec       12006.00 kbits/sec

That looks good :-D

I'm still unable to get wpa_supplicant running, though, but I'm guessing it's a kernel configuration issue. I'll let you know.

mantriyogesh commented 3 days ago

I removed all the logs, set the CLK back to 30Mhz, and configured SPI to MODE_2 but added register_hs_disable_pin as suggested I also rebased on top of master.

Raw TP mode. give me these results :

*** Raw Throughput testing: ESP --> Host started ***

I (3452) stats: 0-1 sec       10844.50 kbits/sec
I (4452) stats: 1-2 sec       12155.50 kbits/sec
I (5452) stats: 2-3 sec       11994.50 kbits/sec
I (6452) stats: 3-4 sec       11914.00 kbits/sec
I (7452) stats: 4-5 sec       12006.00 kbits/sec
I (8452) stats: 5-6 sec       12017.50 kbits/sec
I (9452) stats: 6-7 sec       11431.00 kbits/sec
I (10452) stats: 7-8 sec       12029.00 kbits/sec
I (11452) stats: 8-9 sec       12006.00 kbits/sec

That looks good :-D

I'm still unable to get wpa_supplicant running, though, but I'm guessing it's a kernel configuration issue. I'll let you know.

what is tx and rx raw throughput? I wish to compare tx and rx raw throughput..

12Mbps for 30MHz clock is little less (not very less, so okay). But this might relate to host clock wasting. only taping with logic analyzer can further optimize..

naguirre commented 1 day ago

I tried to measure it but I got only 0kbps and the Host is unresponsive when I load the driver with raw_tp_mode=1 I did more tests with RAW spi and spidev and found some byte corruptions. So, there is definitively something wrong at the HW level. I'll continue to investigate!

mantriyogesh commented 1 day ago

Sure. I think worth to keep https://github.com/espressif/esp-hosted/issues/411#issuecomment-2198265906 and test always in your ESP-Hosted-NG code.

However, lower SPI driver (at the least, spidev full duplex test if supported) should work anyway (ESP-Hosted would also fail if this fails, as you correctly pointed).

What is the 'custom' base Linux hardware? It is just to map similar issues if someone else faces in future.

We would wait for your observations.