ets Jun 8 2016 00:22:57 rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT) configsip: 0, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:2 load:0x3fff0030,len:4 load:0x3fff0034,len:7092 load:0x40078000,len:13696 load:0x40080400,len:4000 0x40080400: _init at ??:? entry 0x40080688 I (28) boot: ESP-IDF v4.2-dev-1126-gd85d3d969 2nd stage bootloader I (29) boot: compile time 02:49:41 I (29) boot: chip revision: 1 I (33) boot_comm: chip revision: 1, min. bootloader chip revision: 0 I (49) boot.esp32: SPI Speed : 40MHz I (49) boot.esp32: SPI Mode : DIO I (49) boot.esp32: SPI Flash Size : 2MB I (54) boot: Enabling RNG early entropy source... I (59) boot: Partition Table: I (63) boot: ## Label Usage Type ST Offset Length I (70) boot: 0 nvs WiFi data 01 02 00009000 00006000 I (77) boot: 1 phy_init RF data 01 01 0000f000 00001000 I (85) boot: 2 factory factory app 00 00 00010000 00100000 I (92) boot: End of partition table I (97) boot_comm: chip revision: 1, min. application chip revision: 0 I (104) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x0e0b0 ( 57520) map I (135) esp_image: segment 1: paddr=0x0001e0d8 vaddr=0x3ffbdb60 size=0x01f40 ( 8000) load I (138) esp_image: segment 2: paddr=0x00020020 vaddr=0x400d0020 size=0x4d798 (317336) map 0x400d0020: _stext at ??:? I (262) esp_image: segment 3: paddr=0x0006d7c0 vaddr=0x3ffbfaa0 size=0x016a0 ( 5792) load I (265) esp_image: segment 4: paddr=0x0006ee68 vaddr=0x40080000 size=0x00404 ( 1028) load 0x40080000: _WindowOverflow4 at G:/ESP32/idf4.2/esp-idf/components/freertos/xtensa/xtensa_vectors.S:1730 I (269) esp_image: segment 5: paddr=0x0006f274 vaddr=0x40080404 size=0x13300 ( 78592) load I (322) boot: Loaded app from partition at offset 0x10000 I (322) boot: Disabling RNG early entropy source... I (323) cpu_start: Pro cpu up. I (326) cpu_start: Application information: I (331) cpu_start: Project name: bleprph I (336) cpu_start: App version: 1 I (341) cpu_start: Compile time: Apr 20 2020 02:49:17 I (347) cpu_start: ELF file SHA256: d92753d18148b35c... I (353) cpu_start: ESP-IDF: v4.2-dev-1126-gd85d3d969 I (359) cpu_start: Starting app cpu, entry point is 0x40081500 0x40081500: call_start_cpu1 at G:/ESP32/idf4.2/esp-idf/components/esp32/cpu_start.c:277 I (0) cpu_start: App cpu up. D (370) memory_layout: Checking 11 reserved memory ranges: D (375) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0 D (381) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10 D (388) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388 D (394) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20 D (401) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c D (407) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffc3d88 D (413) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440 D (420) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350 D (426) memory_layout: Reserved memory range 0x40070000 - 0x40078000 D (433) memory_layout: Reserved memory range 0x40078000 - 0x40080000 0x40080000: _WindowOverflow4 at G:/ESP32/idf4.2/esp-idf/components/freertos/xtensa/xtensa_vectors.S:1730 D (439) memory_layout: Reserved memory range 0x40080000 - 0x40093704 0x40080000: _WindowOverflow4 at G:/ESP32/idf4.2/esp-idf/components/freertos/xtensa/xtensa_vectors.S:1730 D (445) memory_layout: Building list of available memory regions: D (452) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000 D (458) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000 D (465) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28 D (471) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60 D (478) memory_layout: Available memory region 0x3ffc3d88 - 0x3ffc4000 D (485) memory_layout: Available memory region 0x3ffc4000 - 0x3ffc6000 D (491) memory_layout: Available memory region 0x3ffc6000 - 0x3ffc8000 D (498) memory_layout: Available memory region 0x3ffc8000 - 0x3ffca000 D (504) memory_layout: Available memory region 0x3ffca000 - 0x3ffcc000 D (511) memory_layout: Available memory region 0x3ffcc000 - 0x3ffce000 D (518) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000 D (524) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000 D (531) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000 D (537) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000 D (544) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000 D (551) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000 D (557) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000 D (564) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000 D (570) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000 D (577) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20 D (584) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000 D (590) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000 D (597) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000 D (603) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000 D (610) memory_layout: Available memory region 0x3fffc000 - 0x40000000 D (617) memory_layout: Available memory region 0x40093704 - 0x40094000 D (623) memory_layout: Available memory region 0x40094000 - 0x40096000 D (630) memory_layout: Available memory region 0x40096000 - 0x40098000 D (636) memory_layout: Available memory region 0x40098000 - 0x4009a000 D (643) memory_layout: Available memory region 0x4009a000 - 0x4009c000 D (650) memory_layout: Available memory region 0x4009c000 - 0x4009e000 D (656) memory_layout: Available memory region 0x4009e000 - 0x400a0000 I (663) heap_init: Initializing. RAM available for dynamic allocation: D (670) heap_init: New heap initialised at 0x3ffaff10 I (675) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM D (681) heap_init: New heap initialised at 0x3ffb6388 I (686) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM D (692) heap_init: New heap initialised at 0x3ffb9a20 I (697) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (704) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM D (710) heap_init: New heap initialised at 0x3ffc3d88 I (715) heap_init: At 3FFC3D88 len 0001C278 (112 KiB): DRAM I (721) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (727) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM D (734) heap_init: New heap initialised at 0x40093704 I (739) heap_init: At 40093704 len 0000C8FC (50 KiB): IRAM I (745) cpu_start: Pro cpu start user code D (757) clk: RTC_SLOW_CLK calibration value: 3165990 D (767) intr_alloc: Connected src 46 to int 2 (cpu 0) D (767) intr_alloc: Connected src 17 to int 3 (cpu 0) D (768) intr_alloc: Connected src 24 to int 9 (cpu 0) D (773) FLASH_HAL: extra_dummy: 1 D (776) spi_flash: trying chip: issi D bus_lock: SPI dev changed from 0 to 0 D (783) spi_flash: trying chip: gd D bus_lock: SPI dev changed from 0 to 0 D (790) spi_flash: trying chip: mxic D bus_lock: SPI dev changed from 0 to 0 D (797) spi_flash: trying chip: generic D bus_lock: SPI dev changed from 0 to 0 I (805) spi_flash: detected chip: generic D bus_lock: SPI dev changed from 0 to 0 I (813) spi_flash: flash io: dio D bus_lock: SPI dev changed from 0 to 0 D (821) chip_generic: set_io_mode: status before 0x200 D (835) chip_generic: set_io_mode: status after 0x200 W (835) spi_flash: Detected size(4096k) larger than the size in the binary image header(2048k). Using the size in the binary image header. I (844) cpu_start: Starting scheduler on PRO CPU. D (0) intr_alloc: Connected src 25 to int 2 (cpu 1) I (0) cpu_start: Starting scheduler on APP CPU. D (860) heap_init: New heap initialised at 0x3ffe0440 D (870) heap_init: New heap initialised at 0x3ffe4350 D (880) intr_alloc: Connected src 16 to int 12 (cpu 0) D (880) partition: Loading the partition table D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (1180) BTDM_INIT: Release DRAM [0x3ffb2730] - [0x3ffb6388] I (1190) BTDM_INIT: BT controller compile version [d9cc6b1] D (1200) BTDM_INIT: .data initialise [0x3ffae6e0] <== [0x4000d890] D (1200) BTDM_INIT: .bss initialise [0x3ffb0000] - [0x3ffb09a8] D (1210) BTDM_INIT: .bss initialise [0x3ffb09a8] - [0x3ffb1ddc] D (1210) BTDM_INIT: .bss initialise [0x3ffb1ddc] - [0x3ffb2730] D (1220) BTDM_INIT: .bss initialise [0x3ffb8000] - [0x3ffb9a20] D (1230) BTDM_INIT: .bss initialise [0x3ffbdb28] - [0x3ffbdb5c] I (1230) system_api: Base MAC address is not set I (1240) system_api: read default base MAC address from EFUSE D (1240) efuse: coding scheme 0 D (1250) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit D (1250) efuse: coding scheme 0 D (1260) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit D (1260) efuse: coding scheme 0 D (1270) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit D (1270) efuse: coding scheme 0 D (1280) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit D (1280) efuse: coding scheme 0 D (1290) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit D (1300) efuse: coding scheme 0 D (1300) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit D (1310) efuse: coding scheme 0 D (1310) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit D (1320) phy_init: loading PHY init data from application binary D (1320) nvs: nvs_open_from_partition phy 0 D (1330) nvs: nvs_get cal_version 4 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (1340) nvs: nvs_get_str_or_blob cal_mac D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (1360) efuse: coding scheme 0 D (1370) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit D (1370) efuse: coding scheme 0 D (1380) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit D (1380) efuse: coding scheme 0 D (1390) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit D (1390) efuse: coding scheme 0 D (1400) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit D (1400) efuse: coding scheme 0 D (1410) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit D (1410) efuse: coding scheme 0 D (1420) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit D (1420) efuse: coding scheme 0 D (1430) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit D (1430) nvs: nvs_get_str_or_blob cal_data D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (1670) nvs: nvs_close 1 D (1670) efuse: coding scheme 0 D (1680) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit D (1680) efuse: coding scheme 0 D (1690) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit D (1690) efuse: coding scheme 0 D (1700) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit D (1700) efuse: coding scheme 0 D (1710) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit D (1710) efuse: coding scheme 0 D (1720) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit D (1720) efuse: coding scheme 0 D (1730) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit D (1730) efuse: coding scheme 0 D (1740) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit I (1830) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0 D (2060) nvs: nvs_open_from_partition nimble_bond 1 D (2060) nvs: nvs_get_str_or_blob our_sec_1 D (2060) nvs: nvs_close 2 D (2060) nvs: nvs_open_from_partition nimble_bond 1 D (2070) nvs: nvs_get_str_or_blob our_sec_2 D (2070) nvs: nvs_close 3 D (2080) nvs: nvs_open_from_partition nimble_bond 1 D (2080) nvs: nvs_get_str_or_blob our_sec_3 D (2090) nvs: nvs_close 4 D (2090) NIMBLE_NVS: ble_store_config_our_secs restored 0 bonds D (2090) nvs: nvs_open_from_partition nimble_bond 1 D (2100) nvs: nvs_get_str_or_blob peer_sec_1 D (2100) nvs: nvs_close 5 D (2110) nvs: nvs_open_from_partition nimble_bond 1 D (2110) nvs: nvs_get_str_or_blob peer_sec_2 D (2120) nvs: nvs_close 6 D (2120) nvs: nvs_open_from_partition nimble_bond 1 D (2120) nvs: nvs_get_str_or_blob peer_sec_3 D (2130) nvs: nvs_close 7 D (2130) NIMBLE_NVS: ble_store_config_peer_secs restored 0 bonds D (2140) nvs: nvs_open_from_partition nimble_bond 1 D (2140) nvs: nvs_get_str_or_blob cccd_sec_1 D (2150) nvs: nvs_close 8 D (2150) nvs: nvs_open_from_partition nimble_bond 1 D (2150) nvs: nvs_get_str_or_blob cccd_sec_2 D (2160) nvs: nvs_close 9 D (2160) nvs: nvs_open_from_partition nimble_bond 1 D (2170) nvs: nvs_get_str_or_blob cccd_sec_3 D (2170) nvs: nvs_close 10 D (2170) nvs: nvs_open_from_partition nimble_bond 1 D (2180) nvs: nvs_get_str_or_blob cccd_sec_4 D (2180) nvs: nvs_close 11 D (2180) nvs: nvs_open_from_partition nimble_bond 1 D (2190) nvs: nvs_get_str_or_blob cccd_sec_5 D (2190) nvs: nvs_close 12 D (2200) nvs: nvs_open_from_partition nimble_bond 1 D (2200) nvs: nvs_get_str_or_blob cccd_sec_6 D (2210) nvs: nvs_close 13 D (2210) nvs: nvs_open_from_partition nimble_bond 1 D (2210) nvs: nvs_get_str_or_blob cccd_sec_7 D (2220) nvs: nvs_close 14 D (2220) nvs: nvs_open_from_partition nimble_bond 1 D (2230) nvs: nvs_get_str_or_blob cccd_sec_8 D (2230) nvs: nvs_close 15 D (2230) NIMBLE_NVS: ble_store_config_cccds restored 0 bonds D (2240) nvs: nvs_open_from_partition nimble_bond 1 D (2240) nvs: nvs_get_str_or_blob p_dev_rec_1 D (2250) nvs: nvs_close 16 D (2250) nvs: nvs_open_from_partition nimble_bond 1 D (2260) nvs: nvs_get_str_or_blob p_dev_rec_2 D (2260) nvs: nvs_close 17 D (2260) nvs: nvs_open_from_partition nimble_bond 1 D (2270) nvs: nvs_get_str_or_blob p_dev_rec_3 D (2270) nvs: nvs_close 18 D (2280) nvs: nvs_open_from_partition nimble_bond 1 D (2280) nvs: nvs_get_str_or_blob p_dev_rec_4 D (2280) nvs: nvs_close 19 D (2290) NIMBLE_NVS: peer_dev_rec restored 0 records I (2290) NimBLE_BLE_PRPH: BLE Host Task Started registered service 0x1800 with handle=1 registering characteristic 0x2a00 with def_handle=2 val_handle=3 registering characteristic 0x2a01 with def_handle=4 val_handle=5 registered service 0x1801 with handle=6 registering characteristic 0x2a05 with def_handle=7 val_handle=8 registered service 59462f12-9543-9999-12c8-58b459a2712d with handle=10 registering characteristic 5c3a659e-897e-45e1-b016-007107c96df6 with def_handle=11 val_handle=12 registering characteristic 5c3a659e-897e-45e1-b016-007107c96df7 with def_handle=13 val_handle=14 ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0003 len=0 0x03 0x0c 0x00 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x3 status=0 ble_hs_hci_cmd_send: ogf=0x04 ocf=0x0001 len=0 0x01 0x10 0x00 Command complete: cmd_pkts=5 ogf=0x4 ocf=0x1 status=0 hci_ver=8 hci_rev=782 lmp_ver=8 mfrg=96 lmp_subver=782 ble_hs_hci_cmd_send: ogf=0x04 ocf=0x0003 len=0 0x03 0x10 0x00 Command complete: cmd_pkts=5 ogf=0x4 ocf=0x3 status=0 supp_feat=0x877bffdbfecdeebf ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0001 len=8 0x01 0x0c 0x08 0x90 0x80 0x00 0x02 0x00 0x80 0x00 0x20 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x1 status=0 ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0063 len=8 0x63 0x0c 0x08 0x00 0x00 0x80 0x00 0x00 0x00 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x63 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0001 len=8 0x01 0x20 0x08 0x7f 0x06 0x00 0x00 0x00 0x00 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x1 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0002 len=0 0x02 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x2 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0003 len=0 0x03 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x3 status=0 ble_hs_hci_cmd_send: ogf=0x04 ocf=0x0009 len=0 0x09 0x10 0x00 Command complete: cmd_pkts=5 ogf=0x4 ocf=0x9 status=0 bd_addr=24:a:c4:ad:5:2a ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0018 len=0 0x18 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x18 status=0 rand=0x61fca02b56cd1f4f ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0018 len=0 0x18 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x18 status=0 rand=0xe460dfb2c49de0d5 Device added to RL, Resolving list count = 1 ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0031 len=1 0x31 0x0c 0x01 0x01 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x31 status=0 ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0033 len=7 0x33 0x0c 0x07 0xff 0x00 0x00 0x0c 0x00 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x33 status=17 ble_hs_hci_cmd_send: ogf=0x03 ocf=0x0031 len=1 0x31 0x0c 0x01 0x00 Command complete: cmd_pkts=5 ogf=0x3 ocf=0x31 status=0 looking up peer sec; ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0018 len=0 0x18 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x18 status=0 rand=0xe07d0a1358a2c2b2 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0005 len=6 0x05 0x20 0x06 0x71 0xc6 0x2a 0xe0 0x7d 0x4a Command complete: cmd_pkts=5 ogf=0x8 ocf=0x5 status=0 Device Address: 00:00:00:00:00:00 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0007 len=0 0x07 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x7 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0008 len=32 0x08 0x20 0x20 0x1a 0x02 0x01 0x06 0x03 0x03 0x11 0x18 0x0f 0x09 0x6e 0x69 0x6d 0x62 0x6c 0x65 0x2d 0x62 0x6c 0x65 0x70 0x72 0x70 0x68 0x02 0x0a 0x03 0x00 0x00 0x00 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x8 status=0 GAP procedure initiated: advertise; disc_mode=2 adv_channel_map=0 own_addr_type=3 adv_filter_policy=0 adv_itvl_min=0 adv_itvl_max=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0006 len=15 0x06 0x20 0x0f 0x30 0x00 0x60 0x00 0x00 0x03 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x07 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x6 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x000a len=1 0x0a 0x20 0x01 0x01 Command complete: cmd_pkts=5 ogf=0x8 ocf=0xa status=0 looking up peer sec; >>> Store peer count = 0 LE connection complete. handle=0 role=1 paddrtype=1 addr=66.d3.23.f1.49.93 local_rpa=0.0.0.0.0.0 peer_rpa=0.0.0.0.0.0 itvl=24 latency=0 spvn_tmo=72 mca=1 0x93 0x49 0xf1 0x23 0xd3 0x66 connection established; status=0 handle=0 our_ota_addr_type=1 our_ota_addr=4a:7d:e0:2a:c6:71 our_id_addr_type=1 our_id_addr=4a:7d:e0:2a:c6:71 peer_ota_addr_type=1 peer_ota_addr=66:d3:23:f1:49:93 peer_id_addr_type=1 peer_id_addr=66:d3:23:f1:49:93 conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=0 authenticated=0 bonded=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0016 len=2 0x16 0x20 0x02 0x00 0x00 Command Status: status=0 cmd_pkts=5 ocf=0x16 ogf=0x8 LE Remote Used Features. handle=0 feat=dd 00 00 00 00 00 00 00 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x02 0xb9 0x00 rxed att command: mtu req; conn=0 mtu=185 txed att command: mtu rsp; conn=0 mtu=256 host tx hci data; handle=0 length=7 ble_hs_hci_acl_tx(): 0x00 0x00 0x07 0x00 0x03 0x00 0x04 0x00 0x03 0x00 0x01 mtu update event; conn_handle=0 cid=4 mtu=185 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x10 0x01 0x00 0xff 0xff 0x00 0x28 rxed att command: read group type req; conn=0 start_handle=0x0001 end_handle=0xffff txed att command: read group type rsp; conn=0 length=6 host tx hci data; handle=0 length=18 ble_hs_hci_acl_tx(): 0x00 0x00 0x12 0x00 0x0e 0x00 0x04 0x00 0x11 0x06 0x01 0x00 0x05 0x00 0x00 0x18 0x06 0x00 0x09 0x00 0x01 0x18 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x10 0x0a 0x00 0xff 0xff 0x00 0x28 rxed att command: read group type req; conn=0 start_handle=0x000a end_handle=0xffff txed att command: read group type rsp; conn=0 length=20 host tx hci data; handle=0 length=26 ble_hs_hci_acl_tx(): 0x00 0x00 0x1a 0x00 0x16 0x00 0x04 0x00 0x11 0x14 0x0a 0x00 0xff 0xff 0x2d 0x71 0xa2 0x59 0xb4 0x58 0xc8 0x12 0x99 0x99 0x43 0x95 0x12 0x2f 0x46 0x59 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x01 0x00 0x05 0x00 0x00 0x2a rxed att command: read type req; conn=0 start_handle=0x0001 end_handle=0x0005 txed att command: read type rsp; conn=0 length=16 host tx hci data; handle=0 length=22 ble_hs_hci_acl_tx(): 0x00 0x00 0x16 0x00 0x12 0x00 0x04 0x00 0x09 0x10 0x03 0x00 0x6e 0x69 0x6d 0x62 0x6c 0x65 0x2d 0x62 0x6c 0x65 0x70 0x72 0x70 0x68 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x06 0x00 0x09 0x00 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x0006 end_handle=0x0009 txed att command: read type rsp; conn=0 length=7 host tx hci data; handle=0 length=13 ble_hs_hci_acl_tx(): 0x00 0x00 0x0d 0x00 0x09 0x00 0x04 0x00 0x09 0x07 0x07 0x00 0x20 0x08 0x00 0x05 0x2a Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=9 data=0x05 0x00 0x04 0x00 0x04 0x09 0x00 0x09 0x00 rxed att command: find info req; conn=0 start_handle=0x0009 end_handle=0x0009 txed att command: find info rsp; conn=0 format=1 host tx hci data; handle=0 length=10 ble_hs_hci_acl_tx(): 0x00 0x00 0x0a 0x00 0x06 0x00 0x04 0x00 0x05 0x01 0x09 0x00 0x02 0x29 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=9 data=0x05 0x00 0x04 0x00 0x12 0x09 0x00 0x02 0x00 rxed att command: write req; conn=0 handle=0x0009 subscribe event; conn_handle=0 attr_handle=8 reason=1 prevn=0 curn=0 previ=0 curi=1 txed att command: write rsp; conn=0 host tx hci data; handle=0 length=5 ble_hs_hci_acl_tx(): 0x00 0x00 0x05 0x00 0x01 0x00 0x04 0x00 0x13 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x0a 0x00 0xff 0xff 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x000a end_handle=0xffff txed att command: read type rsp; conn=0 length=21 host tx hci data; handle=0 length=48 ble_hs_hci_acl_tx(): 0x00 0x00 0x30 0x00 0x2c 0x00 0x04 0x00 0x09 0x15 0x0b 0x00 0x02 0x0c 0x00 0xf6 0x6d 0xc9 0x07 0x71 0x00 0x16 0xb0 0xe1 0x45 0x7e 0x89 0x9e 0x65 0x3a 0x5c 0x0d 0x00 0x0a 0x0e 0x00 0xf7 0x6d 0xc9 0x07 0x71 0x00 0x16 0xb0 0xe1 0x45 0x7e 0x89 0x9e 0x65 0x3a 0x5c Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x0f 0x00 0xff 0xff 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x000f end_handle=0xffff txed att command: error rsp; conn=0 req_op=8 handle=0x000f error_code=10 host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x01 0x08 0x0f 0x00 0x0a Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x0a 0x0c 0x00 rxed att command: read req; conn=0 handle=0x000c looking up peer sec; peer_addr_type=1 peer_addr=0x93 0x49 0xf1 0x23 0xd3 0x66 txed att command: error rsp; conn=0 req_op=10 handle=0x000c error_code=5 host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x01 0x0a 0x0c 0x00 0x05 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x06 0x00 0x01 0x04 0x00 0x2d 0x10 0x0b 0x0b rxed sm command: pair req; conn=0 io_cap=4 oob_data_flag=0 authreq=0x2d mac_enc_key_size=16 init_key_dist=11 resp_key_dist=11 looking up peer sec; looking up our sec; looking up peer sec; peer_addr_type=1 peer_addr=0x93 0x49 0xf1 0x23 0xd3 0x66 host tx hci data; handle=0 length=11 ble_hs_hci_acl_tx(): 0x00 0x00 0x0b 0x00 0x07 0x00 0x06 0x00 0x02 0x03 0x00 0x0d 0x10 0x00 0x00 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0018 len=0 0x18 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x18 status=0 rand=0x57c50b606cf8924c ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0018 len=0 0x18 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x18 status=0 rand=0xd3034643de51d672 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=27 data=0x41 0x00 0x06 0x00 0x0c 0x50 0xca 0x48 0x45 0xe5 0xd2 0xaf 0xe7 0x5d 0x24 0x7a 0xb0 0xbf 0x9d 0x95 0x4d 0xe0 0x2a 0x3f 0x22 0x69 0xe5 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=1 len=27 data=0x80 0xe2 0x28 0x71 0x2f 0x29 0xa7 0x9f 0x70 0xda 0x37 0xa1 0x4b 0xfa 0x26 0x60 0x8b 0x39 0xef 0xed 0x72 0xc5 0xf3 0x38 0x97 0x2a 0xb6 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=1 len=15 data=0x71 0x21 0x83 0x40 0xfe 0x8e 0xed 0xfe 0xbc 0x07 0x67 0x8c 0x21 0xb7 0xfb our pubkey=0x06 0x0e 0x28 0x34 0xdf 0x7e 0x61 0x24 0xb7 0x20 0x1e 0x72 0x0b 0x6a 0x30 0x46 0xd8 0x50 0x33 0x9d 0xfb 0xce 0x2f 0x39 0x71 0xa5 0x79 0x81 0x95 0xe6 0xa8 0xe0 0x6a 0xec 0x95 0xb2 0x9b 0xae 0x7f 0x9d 0xdc 0x6e 0x72 0x40 0xab 0x21 0xbf 0x2e 0x91 0x20 0x7b 0xb7 0xf1 0xa7 0x23 0x65 0xb1 0x5c 0x70 0x4d 0x3a 0xb4 0x07 0x43 our privkey=0x60 0x65 0x98 0xc9 0xa7 0xcd 0xa1 0xe6 0x0d 0x2a 0x98 0xae 0x98 0xc6 0x7a 0x10 0xcc 0xd5 0x7f 0xe9 0x91 0x05 0x0c 0x66 0x0c 0x44 0x75 0x1e 0x80 0x3b 0x28 0xe6 rxed sm command: public key; conn=0 x=0x50 0xca 0x48 0x45 0xe5 0xd2 0xaf 0xe7 0x5d 0x24 0x7a 0xb0 0xbf 0x9d 0x95 0x4d 0xe0 0x2a 0x3f 0x22 0x69 0xe5 0x80 0xe2 0x28 0x71 0x2f 0x29 0xa7 0x9f 0x70 0xda y=0x37 0xa1 0x4b 0xfa 0x26 0x60 0x8b 0x39 0xef 0xed 0x72 0xc5 0xf3 0x38 0x97 0x2a 0xb6 0x71 0x21 0x83 0x40 0xfe 0x8e 0xed 0xfe 0xbc 0x07 0x67 0x8c 0x21 0xb7 0xfb our pubkey=0x06 0x0e 0x28 0x34 0xdf 0x7e 0x61 0x24 0xb7 0x20 0x1e 0x72 0x0b 0x6a 0x30 0x46 0xd8 0x50 0x33 0x9d 0xfb 0xce 0x2f 0x39 0x71 0xa5 0x79 0x81 0x95 0xe6 0xa8 0xe0 0x6a 0xec 0x95 0xb2 0x9b 0xae 0x7f 0x9d 0xdc 0x6e 0x72 0x40 0xab 0x21 0xbf 0x2e 0x91 0x20 0x7b 0xb7 0xf1 0xa7 0x23 0x65 0xb1 0x5c 0x70 0x4d 0x3a 0xb4 0x07 0x43 our privkey=0x60 0x65 0x98 0xc9 0xa7 0xcd 0xa1 0xe6 0x0d 0x2a 0x98 0xae 0x98 0xc6 0x7a 0x10 0xcc 0xd5 0x7f 0xe9 0x91 0x05 0x0c 0x66 0x0c 0x44 0x75 0x1e 0x80 0x3b 0x28 0xe6 host tx hci data; handle=0 length=69 ble_hs_hci_acl_tx(): 0x00 0x00 0x45 0x00 0x41 0x00 0x06 0x00 0x0c 0x06 0x0e 0x28 0x34 0xdf 0x7e 0x61 0x24 0xb7 0x20 0x1e 0x72 0x0b 0x6a 0x30 0x46 0xd8 0x50 0x33 0x9d 0xfb 0xce 0x2f 0x39 0x71 0xa5 0x79 0x81 0x95 0xe6 0xa8 0xe0 0x6a 0xec 0x95 0xb2 0x9b 0xae 0x7f 0x9d 0xdc 0x6e 0x72 0x40 0xab 0x21 0xbf 0x2e 0x91 0x20 0x7b 0xb7 0xf1 0xa7 0x23 0x65 0xb1 0x5c 0x70 0x4d 0x3a 0xb4 0x07 0x43 ble_sm_alg_f4() u=0x06 0x0e 0x28 0x34 0xdf 0x7e 0x61 0x24 0xb7 0x20 0x1e 0x72 0x0b 0x6a 0x30 0x46 0xd8 0x50 0x33 0x9d 0xfb 0xce 0x2f 0x39 0x71 0xa5 0x79 0x81 0x95 0xe6 0xa8 0xe0 v=0x50 0xca 0x48 0x45 0xe5 0xd2 0xaf 0xe7 0x5d 0x24 0x7a 0xb0 0xbf 0x9d 0x95 0x4d 0xe0 0x2a 0x3f 0x22 0x69 0xe5 0x80 0xe2 0x28 0x71 0x2f 0x29 0xa7 0x9f 0x70 0xda x=0x57 0xc5 0x0b 0x60 0x6c 0xf8 0x92 0x4c 0xd3 0x03 0x46 0x43 0xde 0x51 0xd6 0x72 z=0x00 out_enc_data=0x19 0x44 0x03 0x55 0x6a 0x30 0xf3 0x75 0x5c 0x61 0x12 0xfc 0xc8 0x82 0xd0 0x43 host tx hci data; handle=0 length=21 ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x03 0x19 0x44 0x03 0x55 0x6a 0x30 0xf3 0x75 0x5c 0x61 0x12 0xfc 0xc8 0x82 0xd0 0x43 Number of Completed Packets: num_handles=1 handle:0 pkts:1 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=21 data=0x11 0x00 0x06 0x00 0x04 0x81 0x17 0x37 0x28 0x46 0xa0 0x90 0xf7 0x44 0xd3 0x89 0x34 0x89 0x3c 0xcf 0x89 rxed sm command: random; conn=0 value=0x81 0x17 0x37 0x28 0x46 0xa0 0x90 0xf7 0x44 0xd3 0x89 0x34 0x89 0x3c 0xcf 0x89 ble_sm_alg_f5() w=0x2f 0xf2 0x9b 0x87 0xbb 0xc4 0xe8 0x3b 0x4f 0x02 0xb6 0x67 0xbb 0x18 0x19 0xb4 0x8d 0xe6 0x97 0x1a 0x6e 0x32 0x67 0xcf 0x96 0x07 0xb6 0xe2 0x66 0x19 0x5a 0x97 n1=0x81 0x17 0x37 0x28 0x46 0xa0 0x90 0xf7 0x44 0xd3 0x89 0x34 0x89 0x3c 0xcf 0x89 n2=0x57 0xc5 0x0b 0x60 0x6c 0xf8 0x92 0x4c 0xd3 0x03 0x46 0x43 0xde 0x51 0xd6 0x72 t=0x53 0xdf 0x98 0x48 0x98 0x72 0x8e 0xb1 0x71 0xec 0x7f 0x5d 0x94 0x4d 0xab 0x5c mackey=0x8a 0x50 0xc8 0xd1 0x3d 0x5e 0xa9 0x6f 0x3d 0x0b 0x7f 0x57 0x8a 0x0f 0x5d 0x42 ltk=0x36 0xdf 0xc5 0x43 0xfc 0x1f 0xc0 0x3e 0xcf 0xb4 0xd7 0x5f 0x3f 0xb9 0x6d 0x52 host tx hci data; handle=0 length=21 ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x04 0x57 0xc5 0x0b 0x60 0x6c 0xf8 0x92 0x4c 0xd3 0x03 0x46 0x43 0xde 0x51 0xd6 0x72 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=21 data=0x11 0x00 0x06 0x00 0x0d 0xa7 0x67 0x48 0xe6 0x5d 0x65 0xd5 0x5a 0x37 0x05 0x56 0xb1 0x8b 0x49 0xb3 0x07 rxed sm command: dhkey check; conn=0 value=0xa7 0x67 0x48 0xe6 0x5d 0x65 0xd5 0x5a 0x37 0x05 0x56 0xb1 0x8b 0x49 0xb3 0x07 tk=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 ble_sm_alg_f6() w=0x42 0x5d 0x0f 0x8a 0x57 0x7f 0x0b 0x3d 0x6f 0xa9 0x5e 0x3d 0xd1 0xc8 0x50 0x8a n1=0x81 0x17 0x37 0x28 0x46 0xa0 0x90 0xf7 0x44 0xd3 0x89 0x34 0x89 0x3c 0xcf 0x89 n2=0x57 0xc5 0x0b 0x60 0x6c 0xf8 0x92 0x4c 0xd3 0x03 0x46 0x43 0xde 0x51 0xd6 0x72 r=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 iocap=0x04 0x00 0x2d a1t=0x01 a1=0x93 0x49 0xf1 0x23 0xd3 0x66 a2t=0x01 a2=0x71 0xc6 0x2a 0xe0 0x7d 0x4a res=0x07 0xb3 0x49 0x8b 0xb1 0x56 0x05 0x37 0x5a 0xd5 0x65 0x5d 0xe6 0x48 0x67 0xa7 ble_sm_alg_f6() w=0x42 0x5d 0x0f 0x8a 0x57 0x7f 0x0b 0x3d 0x6f 0xa9 0x5e 0x3d 0xd1 0xc8 0x50 0x8a n1=0x57 0xc5 0x0b 0x60 0x6c 0xf8 0x92 0x4c 0xd3 0x03 0x46 0x43 0xde 0x51 0xd6 0x72 n2=0x81 0x17 0x37 0x28 0x46 0xa0 0x90 0xf7 0x44 0xd3 0x89 0x34 0x89 0x3c 0xcf 0x89 r=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 iocap=0x03 0x00 0x0d a1t=0x01 a1=0x71 0xc6 0x2a 0xe0 0x7d 0x4a a2t=0x01 a2=0x93 0x49 0xf1 0x23 0xd3 0x66 res=0x7e 0x85 0xdf 0x89 0x92 0x43 0x8e 0x50 0x44 0x12 0x74 0x3b 0xf0 0x38 0x08 0x5f host tx hci data; handle=0 length=21 ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x0d 0x5f 0x08 0x38 0xf0 0x3b 0x74 0x12 0x44 0x50 0x8e 0x43 0x92 0x89 0xdf 0x85 0x7e Number of Completed Packets: num_handles=1 handle:0 pkts:1 LE LTK Req. handle=0 rand=00 encdiv=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x001a len=18 0x1a 0x20 0x12 0x00 0x00 0x52 0x6d 0xb9 0x3f 0x5f 0xd7 0xb4 0xcf 0x3e 0xc0 0x1f 0xfc 0x43 0xc5 0xdf 0x36 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x1a status=0 handle=0 Encrypt change: status=0 handle=0 state=1 encryption change event; status=0 handle=0 our_ota_addr_type=1 our_ota_addr=4a:7d:e0:2a:c6:71 our_id_addr_type=1 our_id_addr=4a:7d:e0:2a:c6:71 peer_ota_addr_type=1 peer_ota_addr=66:d3:23:f1:49:93 peer_id_addr_type=1 peer_id_addr=66:d3:23:f1:49:93 conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=1 authenticated=0 bonded=1 D (26980) nvs: nvs_open_from_partition nimble_bond 1 D (26990) nvs: nvs_get_str_or_blob cccd_sec_1 D (26990) nvs: nvs_close 20 D (26990) nvs: nvs_open_from_partition nimble_bond 1 D (27000) nvs: nvs_get_str_or_blob cccd_sec_2 D (27000) nvs: nvs_close 21 D (27010) nvs: nvs_open_from_partition nimble_bond 1 D (27010) nvs: nvs_get_str_or_blob cccd_sec_3 D (27020) nvs: nvs_close 22 D (27020) nvs: nvs_open_from_partition nimble_bond 1 D (27020) nvs: nvs_get_str_or_blob cccd_sec_4 D (27030) nvs: nvs_close 23 D (27030) nvs: nvs_open_from_partition nimble_bond 1 D (27040) nvs: nvs_get_str_or_blob cccd_sec_5 D (27040) nvs: nvs_close 24 D (27040) nvs: nvs_open_from_partition nimble_bond 1 D (27050) nvs: nvs_get_str_or_blob cccd_sec_6 D (27050) nvs: nvs_close 25 D (27060) nvs: nvs_open_from_partition nimble_bond 1 D (27060) nvs: nvs_get_str_or_blob cccd_sec_7 D (27070) nvs: nvs_close 26 D (27070) nvs: nvs_open_from_partition nimble_bond 1 D (27070) nvs: nvs_get_str_or_blob cccd_sec_8 D (27080) nvs: nvs_close 27 D (27080) NIMBLE_NVS: Persisting CCCD value in NVS... D (27090) nvs: nvs_open_from_partition nimble_bond 1 D (27090) nvs: nvs_get_str_or_blob cccd_sec_1 D (27090) nvs: nvs_close 28 D (27100) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 3 D (27100) nvs: nvs_open_from_partition nimble_bond 1 D (27110) nvs: nvs_set_blob cccd_sec_1 16 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (27140) nvs: nvs_close 29 persisting our sec; ediv=0 rand=0 authenticated=0 ltk=0x52 0x6d 0xb9 0x3f 0x5f 0xd7 0xb4 0xcf 0x3e 0xc0 0x1f 0xfc 0x43 0xc5 0xdf 0x36 D (27150) nvs: nvs_open_from_partition nimble_bond 1 D (27160) nvs: nvs_get_str_or_blob our_sec_1 D (27160) nvs: nvs_close 30 D (27170) nvs: nvs_open_from_partition nimble_bond 1 D (27170) nvs: nvs_get_str_or_blob our_sec_2 D (27170) nvs: nvs_close 31 D (27180) nvs: nvs_open_from_partition nimble_bond 1 D (27180) nvs: nvs_get_str_or_blob our_sec_3 D (27190) nvs: nvs_close 32 D (27190) NIMBLE_NVS: Persisting our sec value to NVS... D (27200) nvs: nvs_open_from_partition nimble_bond 1 D (27200) nvs: nvs_get_str_or_blob our_sec_1 D (27200) nvs: nvs_close 33 D (27210) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 1 D (27210) nvs: nvs_open_from_partition nimble_bond 1 D (27220) nvs: nvs_set_blob our_sec_1 80 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (27250) nvs: nvs_close 34 persisting peer sec; ediv=0 rand=0 authenticated=0 ltk=0x52 0x6d 0xb9 0x3f 0x5f 0xd7 0xb4 0xcf 0x3e 0xc0 0x1f 0xfc 0x43 0xc5 0xdf 0x36 D (27270) nvs: nvs_open_from_partition nimble_bond 1 D (27270) nvs: nvs_get_str_or_blob peer_sec_1 D (27280) nvs: nvs_close 35 D (27280) nvs: nvs_open_from_partition nimble_bond 1 D (27290) nvs: nvs_get_str_or_blob peer_sec_2 D (27290) nvs: nvs_close 36 D (27290) nvs: nvs_open_from_partition nimble_bond 1 D (27300) nvs: nvs_get_str_or_blob peer_sec_3 D (27300) nvs: nvs_close 37 D (27310) NIMBLE_NVS: Persisting peer sec value in NVS... D (27310) nvs: nvs_open_from_partition nimble_bond 1 D (27320) nvs: nvs_get_str_or_blob peer_sec_1 D (27320) nvs: nvs_close 38 D (27320) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 2 D (27330) nvs: nvs_open_from_partition nimble_bond 1 D (27330) nvs: nvs_set_blob peer_sec_1 80 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D bus_lock: SPI dev changed from 0 to 0 D (27370) nvs: nvs_close 39 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x0a 0x0c 0x00 rxed att command: read req; conn=0 handle=0x000c host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x0b 0x2d 0xf4 0x51 0x58 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=9 data=0x05 0x00 0x04 0x00 0x04 0x0f 0x00 0xff 0xff rxed att command: find info req; conn=0 start_handle=0x000f end_handle=0xffff txed att command: error rsp; conn=0 req_op=4 handle=0x000f error_code=10 host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x01 0x04 0x0f 0x00 0x0a Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x0a 0x0e 0x00 rxed att command: read req; conn=0 handle=0x000e host tx hci data; handle=0 length=6 ble_hs_hci_acl_tx(): 0x00 0x00 0x06 0x00 0x02 0x00 0x04 0x00 0x0b 0x00 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x01 0x00 0x05 0x00 0x00 0x2a rxed att command: read type req; conn=0 start_handle=0x0001 end_handle=0x0005 txed att command: read type rsp; conn=0 length=16 host tx hci data; handle=0 length=22 ble_hs_hci_acl_tx(): 0x00 0x00 0x16 0x00 0x12 0x00 0x04 0x00 0x09 0x10 0x03 0x00 0x6e 0x69 0x6d 0x62 0x6c 0x65 0x2d 0x62 0x6c 0x65 0x70 0x72 0x70 0x68 Number of Completed Packets: num_handles=1 handle:0 pkts:1 Disconnection Complete: status=0 handle=0 reason=19 subscribe event; conn_handle=0 attr_handle=8 reason=2 prevn=0 curn=0 previ=1 curi=0 disconnect; reason=531 handle=0 our_ota_addr_type=1 our_ota_addr=4a:7d:e0:2a:c6:71 our_id_addr_type=1 our_id_addr=4a:7d:e0:2a:c6:71 peer_ota_addr_type=1 peer_ota_addr=66:d3:23:f1:49:93 peer_id_addr_type=1 peer_id_addr=66:d3:23:f1:49:93 conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=1 authenticated=0 bonded=1 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0007 len=0 0x07 0x20 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x7 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0008 len=32 0x08 0x20 0x20 0x1a 0x02 0x01 0x06 0x03 0x03 0x11 0x18 0x0f 0x09 0x6e 0x69 0x6d 0x62 0x6c 0x65 0x2d 0x62 0x6c 0x65 0x70 0x72 0x70 0x68 0x02 0x0a 0x03 0x00 0x00 0x00 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x8 status=0 GAP procedure initiated: advertise; disc_mode=2 adv_channel_map=0 own_addr_type=3 adv_filter_policy=0 adv_itvl_min=0 adv_itvl_max=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0006 len=15 0x06 0x20 0x0f 0x30 0x00 0x60 0x00 0x00 0x03 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x07 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x6 status=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x000a len=1 0x0a 0x20 0x01 0x01 Command complete: cmd_pkts=5 ogf=0x8 ocf=0xa status=0 looking up peer sec; looking up peer sec; >>> Store peer count = 1 LE connection complete. handle=0 role=1 paddrtype=1 addr=47.10.6e.7c.a8.20 local_rpa=0.0.0.0.0.0 peer_rpa=0.0.0.0.0.0 itvl=24 latency=0 spvn_tmo=72 mca=1 0x20 0xa8 0x7c 0x6e 0x10 0x47 connection established; status=0 handle=0 our_ota_addr_type=1 our_ota_addr=4a:7d:e0:2a:c6:71 our_id_addr_type=1 our_id_addr=4a:7d:e0:2a:c6:71 peer_ota_addr_type=1 peer_ota_addr=47:10:6e:7c:a8:20 peer_id_addr_type=1 peer_id_addr=47:10:6e:7c:a8:20 conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=0 authenticated=0 bonded=0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0016 len=2 0x16 0x20 0x02 0x00 0x00 Command Status: status=0 cmd_pkts=5 ocf=0x16 ogf=0x8 LE LTK Req. handle=0 rand=00 encdiv=0 looking up our sec; peer_addr_type=1 peer_addr=0x20 0xa8 0x7c 0x6e 0x10 0x47 ediv=0x00 rand=0x0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x001b len=2 0x1b 0x20 0x02 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x1b status=0 handle=0 LE Remote Used Features. handle=0 feat=dd 00 00 00 00 00 00 00 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x02 0xb9 0x00 rxed att command: mtu req; conn=0 mtu=185 txed att command: mtu rsp; conn=0 mtu=256 host tx hci data; handle=0 length=7 ble_hs_hci_acl_tx(): 0x00 0x00 0x07 0x00 0x03 0x00 0x04 0x00 0x03 0x00 0x01 mtu update event; conn_handle=0 cid=4 mtu=185 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x10 0x01 0x00 0xff 0xff 0x00 0x28 rxed att command: read group type req; conn=0 start_handle=0x0001 end_handle=0xffff txed att command: read group type rsp; conn=0 length=6 host tx hci data; handle=0 length=18 ble_hs_hci_acl_tx(): 0x00 0x00 0x12 0x00 0x0e 0x00 0x04 0x00 0x11 0x06 0x01 0x00 0x05 0x00 0x00 0x18 0x06 0x00 0x09 0x00 0x01 0x18 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x10 0x0a 0x00 0xff 0xff 0x00 0x28 rxed att command: read group type req; conn=0 start_handle=0x000a end_handle=0xffff txed att command: read group type rsp; conn=0 length=20 host tx hci data; handle=0 length=26 ble_hs_hci_acl_tx(): 0x00 0x00 0x1a 0x00 0x16 0x00 0x04 0x00 0x11 0x14 0x0a 0x00 0xff 0xff 0x2d 0x71 0xa2 0x59 0xb4 0x58 0xc8 0x12 0x99 0x99 0x43 0x95 0x12 0x2f 0x46 0x59 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x06 0x00 0x09 0x00 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x0006 end_handle=0x0009 txed att command: read type rsp; conn=0 length=7 host tx hci data; handle=0 length=13 ble_hs_hci_acl_tx(): 0x00 0x00 0x0d 0x00 0x09 0x00 0x04 0x00 0x09 0x07 0x07 0x00 0x20 0x08 0x00 0x05 0x2a Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=9 data=0x05 0x00 0x04 0x00 0x04 0x09 0x00 0x09 0x00 rxed att command: find info req; conn=0 start_handle=0x0009 end_handle=0x0009 txed att command: find info rsp; conn=0 format=1 host tx hci data; handle=0 length=10 ble_hs_hci_acl_tx(): 0x00 0x00 0x0a 0x00 0x06 0x00 0x04 0x00 0x05 0x01 0x09 0x00 0x02 0x29 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=9 data=0x05 0x00 0x04 0x00 0x12 0x09 0x00 0x02 0x00 rxed att command: write req; conn=0 handle=0x0009 subscribe event; conn_handle=0 attr_handle=8 reason=1 prevn=0 curn=0 previ=0 curi=1 txed att command: write rsp; conn=0 host tx hci data; handle=0 length=5 ble_hs_hci_acl_tx(): 0x00 0x00 0x05 0x00 0x01 0x00 0x04 0x00 0x13 Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x0a 0x00 0xff 0xff 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x000a end_handle=0xffff txed att command: read type rsp; conn=0 length=21 host tx hci data; handle=0 length=48 ble_hs_hci_acl_tx(): 0x00 0x00 0x30 0x00 0x2c 0x00 0x04 0x00 0x09 0x15 0x0b 0x00 0x02 0x0c 0x00 0xf6 0x6d 0xc9 0x07 0x71 0x00 0x16 0xb0 0xe1 0x45 0x7e 0x89 0x9e 0x65 0x3a 0x5c 0x0d 0x00 0x0a 0x0e 0x00 0xf7 0x6d 0xc9 0x07 0x71 0x00 0x16 0xb0 0xe1 0x45 0x7e 0x89 0x9e 0x65 0x3a 0x5c Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=11 data=0x07 0x00 0x04 0x00 0x08 0x0f 0x00 0xff 0xff 0x03 0x28 rxed att command: read type req; conn=0 start_handle=0x000f end_handle=0xffff txed att command: error rsp; conn=0 req_op=8 handle=0x000f error_code=10 host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x01 0x08 0x0f 0x00 0x0a Number of Completed Packets: num_handles=1 handle:0 pkts:1 ble_hs_hci_evt_acl_process(): conn_handle=0 pb=2 len=7 data=0x03 0x00 0x04 0x00 0x0a 0x0c 0x00 rxed att command: read req; conn=0 handle=0x000c looking up peer sec; peer_addr_type=1 peer_addr=0x20 0xa8 0x7c 0x6e 0x10 0x47 txed att command: error rsp; conn=0 req_op=10 handle=0x000c error_code=5 host tx hci data; handle=0 length=9 ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x01 0x0a 0x0c 0x00 0x05 Number of Completed Packets: num_handles=1 handle:0 pkts:1 LE LTK Req. handle=0 rand=00 encdiv=0 looking up our sec; peer_addr_type=1 peer_addr=0x20 0xa8 0x7c 0x6e 0x10 0x47 ediv=0x00 rand=0x0 ble_hs_hci_cmd_send: ogf=0x08 ocf=0x001b len=2 0x1b 0x20 0x02 0x00 0x00 Command complete: cmd_pkts=5 ogf=0x8 ocf=0x1b status=0 handle=0