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:0x3fff0018,len:4
load:0x3fff001c,len:7088
ho 0 tail 12 room 4
load:0x40078000,len:14792
ho 0 tail 12 room 4
load:0x40080400,len:4468
entry 0x400806ec
I (76) boot: Chip Revision: 1
I (76) boot_comm: chip revision: 1, min. bootloader chip revision: 0
I (43) boot: ESP-IDF v4.1-dev-815-ga45e99853-dirty 2nd stage bootloader
I (43) boot: compile time 23:08:32
I (44) boot: Enabling RNG early entropy source...
I (50) boot: SPI Speed      : 40MHz
I (54) boot: SPI Mode       : DIO
I (58) boot: SPI Flash Size : 4MB
I (62) boot: Partition Table:
I (65) boot: ## Label            Usage          Type ST Offset   Length
I (73) boot:  0 nvs              WiFi data        01 02 00009000 00006000
I (80) boot:  1 phy_init         RF data          01 01 0000f000 00001000
I (88) boot:  2 factory          factory app      00 00 00010000 00100000
I (95) boot: End of partition table
I (99) boot_comm: chip revision: 1, min. application chip revision: 0
I (106) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x0ee7c ( 61052) map
I (137) esp_image: segment 1: paddr=0x0001eea4 vaddr=0x3ffbdb60 size=0x01174 (  4468) load
I (139) esp_image: segment 2: paddr=0x00020020 vaddr=0x400d0020 size=0x4acf4 (306420) map
0x400d0020: _stext at ??:?
I (253) esp_image: segment 3: paddr=0x0006ad1c vaddr=0x3ffbecd4 size=0x01cc4 (  7364) load
I (257) esp_image: segment 4: paddr=0x0006c9e8 vaddr=0x40080000 size=0x00400 (  1024) load
0x40080000: _WindowOverflow4 at G:/ESP32/esp-idf/components/freertos/xtensa_vectors.S:1778
I (261) esp_image: segment 5: paddr=0x0006cdf0 vaddr=0x40080400 size=0x12590 ( 75152) load
I (312) boot: Loaded app from partition at offset 0x10000
I (312) boot: Disabling RNG early entropy source...
I (312) cpu_start: Pro cpu up.
I (316) cpu_start: Application information:
I (321) cpu_start: Project name:     bleprph
I (326) cpu_start: App version:      1
I (330) cpu_start: Compile time:     Apr 16 2020 23:08:48
I (336) cpu_start: ELF file SHA256:  52966469beb2f418...
I (342) cpu_start: ESP-IDF:          v4.1-dev-815-ga45e99853-dirty
I (349) cpu_start: Starting app cpu, entry point is 0x40081180
0x40081180: call_start_cpu1 at G:/ESP32/esp-idf/components/esp32/cpu_start.c:276
I (0) cpu_start: App cpu up.
D (360) memory_layout: Checking 11 reserved memory ranges:
D (365) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (371) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10
D (378) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388
D (384) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20
D (391) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c
D (397) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffc3d48
D (403) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (410) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (416) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (423) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at G:/ESP32/esp-idf/components/freertos/xtensa_vectors.S:1778
D (429) memory_layout: Reserved memory range 0x40080000 - 0x40092990
0x40080000: _WindowOverflow4 at G:/ESP32/esp-idf/components/freertos/xtensa_vectors.S:1778
D (436) memory_layout: Building list of available memory regions:
D (442) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000
D (448) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000
D (455) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28
D (461) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60
D (468) memory_layout: Available memory region 0x3ffc3d48 - 0x3ffc4000
D (475) memory_layout: Available memory region 0x3ffc4000 - 0x3ffc6000
D (481) memory_layout: Available memory region 0x3ffc6000 - 0x3ffc8000
D (488) memory_layout: Available memory region 0x3ffc8000 - 0x3ffca000
D (494) memory_layout: Available memory region 0x3ffca000 - 0x3ffcc000
D (501) memory_layout: Available memory region 0x3ffcc000 - 0x3ffce000
D (508) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
D (514) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
D (521) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
D (527) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
D (534) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
D (541) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
D (547) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
D (554) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
D (560) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
D (567) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
D (574) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
D (580) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
D (587) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
D (593) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
D (600) memory_layout: Available memory region 0x3fffc000 - 0x40000000
D (607) memory_layout: Available memory region 0x40092990 - 0x40094000
D (613) memory_layout: Available memory region 0x40094000 - 0x40096000
D (620) memory_layout: Available memory region 0x40096000 - 0x40098000
D (626) memory_layout: Available memory region 0x40098000 - 0x4009a000
D (633) memory_layout: Available memory region 0x4009a000 - 0x4009c000
D (640) memory_layout: Available memory region 0x4009c000 - 0x4009e000
D (646) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (653) heap_init: Initializing. RAM available for dynamic allocation:
D (660) heap_init: New heap initialised at 0x3ffaff10
I (665) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
D (671) heap_init: New heap initialised at 0x3ffb6388
I (676) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
D (682) heap_init: New heap initialised at 0x3ffb9a20
I (688) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (694) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM
D (700) heap_init: New heap initialised at 0x3ffc3d48
I (705) heap_init: At 3FFC3D48 len 0001C2B8 (112 KiB): DRAM
I (711) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (717) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (724) heap_init: New heap initialised at 0x40092990
I (729) heap_init: At 40092990 len 0000D670 (53 KiB): IRAM
I (735) cpu_start: Pro cpu start user code
D (747) clk: RTC_SLOW_CLK calibration value: 3484531
D (757) intr_alloc: Connected src 46 to int 2 (cpu 0)
D (758) intr_alloc: Connected src 57 to int 3 (cpu 0)
D (759) intr_alloc: Connected src 24 to int 9 (cpu 0)
D (763) FLASH_HAL: extra_dummy: 1
D (766) spi_flash: trying chip: issi
D (770) spi_flash: trying chip: gd
D (773) spi_flash: trying chip: generic
I (777) spi_flash: detected chip: generic
I (782) spi_flash: flash io: dio
D (786) chip_generic: set_io_mode: status before 0x0
I (791) 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 (806) heap_init: New heap initialised at 0x3ffe0440
D (816) heap_init: New heap initialised at 0x3ffe4350
D (826) intr_alloc: Connected src 16 to int 12 (cpu 0)
D (826) partition: Loading the partition table
D (836) nvs: nvs_flash_init_custom partition=nvs start=9 count=6
D (846) BTDM_INIT: Release DRAM [0x3ffb2730] - [0x3ffb6388]
I (846) BTDM_INIT: BT controller compile version [6decaaf]
D (856) BTDM_INIT: .data initialise [0x3ffae6e0] <== [0x4000d890]
D (856) BTDM_INIT: .bss initialise [0x3ffb0000] - [0x3ffb09a8]
D (866) BTDM_INIT: .bss initialise [0x3ffb09a8] - [0x3ffb1ddc]
D (866) BTDM_INIT: .bss initialise [0x3ffb1ddc] - [0x3ffb2730]
D (876) BTDM_INIT: .bss initialise [0x3ffb8000] - [0x3ffb9a20]
D (876) BTDM_INIT: .bss initialise [0x3ffbdb28] - [0x3ffbdb5c]
I (886) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (896) efuse: coding scheme 0
D (896) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (906) efuse: coding scheme 0
D (906) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (916) efuse: coding scheme 0
D (916) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (926) efuse: coding scheme 0
D (926) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (936) efuse: coding scheme 0
D (936) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (946) efuse: coding scheme 0
D (946) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (956) efuse: coding scheme 0
D (956) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (966) phy_init: loading PHY init data from application binary
D (976) nvs: nvs_open_from_partition phy 0
D (976) nvs: nvs_get cal_version 4
D (986) nvs: nvs_get_str_or_blob cal_mac
D (986) efuse: coding scheme 0
D (986) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (996) efuse: coding scheme 0
D (996) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1006) efuse: coding scheme 0
D (1006) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1016) efuse: coding scheme 0
D (1016) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1026) efuse: coding scheme 0
D (1026) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1036) efuse: coding scheme 0
D (1036) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1046) efuse: coding scheme 0
D (1046) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1056) nvs: nvs_get_str_or_blob cal_data
D (1066) nvs: nvs_close 1
D (1066) efuse: coding scheme 0
D (1066) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1076) efuse: coding scheme 0
D (1076) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1086) efuse: coding scheme 0
D (1086) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1096) efuse: coding scheme 0
D (1096) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1106) efuse: coding scheme 0
D (1106) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1116) efuse: coding scheme 0
D (1116) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1126) efuse: coding scheme 0
D (1126) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (1216) phy: phy_version: 4102, 2fa7a43, Jul 15 2019, 13:06:06, 0, 0
D (1446) nvs: nvs_open_from_partition nimble_bond 1
D (1456) nvs: nvs_get_str_or_blob our_sec_1
D (1456) nvs: nvs_close 2
D (1456) nvs: nvs_open_from_partition nimble_bond 1
D (1456) nvs: nvs_get_str_or_blob our_sec_2
D (1466) nvs: nvs_close 3
D (1466) nvs: nvs_open_from_partition nimble_bond 1
D (1466) nvs: nvs_get_str_or_blob our_sec_3
D (1476) nvs: nvs_close 4
D (1476) NIMBLE_NVS: ble_store_config_our_secs restored 0 bonds
D (1486) nvs: nvs_open_from_partition nimble_bond 1
D (1486) nvs: nvs_get_str_or_blob peer_sec_1
D (1486) nvs: nvs_close 5
D (1496) nvs: nvs_open_from_partition nimble_bond 1
D (1496) nvs: nvs_get_str_or_blob peer_sec_2
D (1506) nvs: nvs_close 6
D (1506) nvs: nvs_open_from_partition nimble_bond 1
D (1506) nvs: nvs_get_str_or_blob peer_sec_3
D (1516) nvs: nvs_close 7
D (1516) NIMBLE_NVS: ble_store_config_peer_secs restored 0 bonds
D (1526) nvs: nvs_open_from_partition nimble_bond 1
D (1526) nvs: nvs_get_str_or_blob cccd_sec_1
D (1536) nvs: nvs_close 8
D (1536) nvs: nvs_open_from_partition nimble_bond 1
D (1536) nvs: nvs_get_str_or_blob cccd_sec_2
D (1546) nvs: nvs_close 9
D (1546) nvs: nvs_open_from_partition nimble_bond 1
D (1556) nvs: nvs_get_str_or_blob cccd_sec_3
D (1556) nvs: nvs_close 10
D (1556) nvs: nvs_open_from_partition nimble_bond 1
D (1566) nvs: nvs_get_str_or_blob cccd_sec_4
D (1566) nvs: nvs_close 11
D (1576) nvs: nvs_open_from_partition nimble_bond 1
D (1576) nvs: nvs_get_str_or_blob cccd_sec_5
D (1586) nvs: nvs_close 12
D (1586) nvs: nvs_open_from_partition nimble_bond 1
D (1586) nvs: nvs_get_str_or_blob cccd_sec_6
D (1596) nvs: nvs_close 13
D (1596) nvs: nvs_open_from_partition nimble_bond 1
D (1596) nvs: nvs_get_str_or_blob cccd_sec_7
D (1606) nvs: nvs_close 14
D (1606) nvs: nvs_open_from_partition nimble_bond 1
D (1616) nvs: nvs_get_str_or_blob cccd_sec_8
D (1616) nvs: nvs_close 15
D (1616) NIMBLE_NVS: ble_store_config_cccds restored 0 bonds
D (1626) nvs: nvs_open_from_partition nimble_bond 1
D (1626) nvs: nvs_get_str_or_blob p_dev_rec_1
D (1636) nvs: nvs_close 16
D (1636) nvs: nvs_open_from_partition nimble_bond 1
D (1646) nvs: nvs_get_str_or_blob p_dev_rec_2
D (1646) nvs: nvs_close 17
D (1646) nvs: nvs_open_from_partition nimble_bond 1
D (1656) nvs: nvs_get_str_or_blob p_dev_rec_3
D (1656) nvs: nvs_close 18
D (1666) nvs: nvs_open_from_partition nimble_bond 1
D (1666) nvs: nvs_get_str_or_blob p_dev_rec_4
D (1676) nvs: nvs_close 19
D (1676) NIMBLE_NVS: peer_dev_rec restored 0 records
I (1676) 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=4c:11:ae:c9:53:de
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=0xa039c3fb9ea8206e
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=0x862dcd0767d0d5b0
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; 
I (1916) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)
*** RPA INIT ***
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=0x28efda737ed5725a
ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0005 len=6
0x05 0x20 0x06 0x04 0xfc 0xa6 0x28 0xef 0x5a 
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=1 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 0x01 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; 
I (6116) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)

>>> Store peer count = 0
LE connection complete. handle=0 role=1 paddrtype=1 addr=42.e.61.8e.88.af 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
*** RPA *** enabled
0xaf 0x88 0x8e 0x61 0x0e 0x42 connection established; status=0 *** RPA *** enabled
*** RPA *** enabled
handle=0 our_ota_addr_type=1 our_ota_addr=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af 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
W (39696) NimBLE: !!! ble_att_svr_check_perms: handle_id 3:... 
I (39696) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
W (39786) NimBLE: !!! ble_att_svr_check_perms: handle_id 7:... 
I (39786) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
W (39926) NimBLE: !!! ble_att_svr_check_perms: handle_id 9:... 
I (39936) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
W (40056) NimBLE: !!! ble_att_svr_check_perms: handle_id 11:... 
I (40056) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
W (40066) NimBLE: !!! ble_att_svr_check_perms: handle_id 13:... 
I (40076) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
W (40256) NimBLE: !!! ble_att_svr_check_perms: handle_id 12:... 
*** RPA *** enabled
looking up peer sec; peer_addr_type=1 peer_addr=0xaf 0x88 0x8e 0x61 0x0e 0x42  
I (40276) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)
I (40286) NimBLE: !!! ble_store_read_peer_sec: rc = 5
I (40286) NimBLE: !!! ble_att_svr_check_perms: LTK - insufficient authen (rc = 5)
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; 
I (40386) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)
looking up our sec; 
I (40396) NimBLE: $$$ ble_store_config_read: obj type OUR SEC (rc = 5)
*** RPA *** enabled
*** RPA *** enabled
looking up peer sec; peer_addr_type=1 peer_addr=0xaf 0x88 0x8e 0x61 0x0e 0x42  
I (40416) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)
I (40426) NimBLE: !!! ble_store_read_peer_sec: rc = 5
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=0x0451684b834fddda
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=0xc0c609db7d42777b
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 0xce 0x9d 0x48 0xbd 0x04 0x03 0x74 0x24 0x60 0x19 0xfa 0x7a 0x08 0xdc 0xd9 0xf8 0xc8 0x57 0x3e 0xf5 0x73 0xa9 
ble_hs_hci_evt_acl_process(): conn_handle=0 pb=1 len=27 data=0xb3 0x5c 0xae 0xa4 0xa5 0xb6 0x17 0xeb 0x86 0xdf 0x3f 0xc3 0x51 0x42 0x4b 0xbb 0x01 0x89 0x11 0x06 0xd8 0x64 0xa3 0x2e 0x01 0x30 0x8e 
ble_hs_hci_evt_acl_process(): conn_handle=0 pb=1 len=15 data=0x10 0x24 0x03 0x78 0x88 0x47 0xf5 0x6d 0xd2 0xf2 0xd1 0xf9 0x5d 0xa7 0xa9 
our pubkey=0xd8 0xe1 0x3b 0x37 0x76 0x23 0x25 0xf3 0xd5 0x24 0x7c 0xd0 0x15 0x53 0x6a 0x24 0xaa 0xcf 0xab 0xfb 0xc7 0x6f 0x00 0x4e 0x72 0x83 0xf6 0x55 0x48 0xe2 0x8e 0xf4 0x62 0xea 0xe0 0xc2 0x28 0x03 0xc3 0x5f 0x5e 0xe5 0x02 0x61 0x58 0xc9 0xa8 0xee 0x68 0x02 0x90 0x99 0x1d 0x4f 0x04 0x56 0xb8 0x62 0xe7 0x5e 0x56 0x5b 0xdf 0x41 
our privkey=0xc0 0x71 0xa1 0xcc 0xf5 0xa0 0x91 0x11 0xc3 0x37 0x3d 0xac 0xc8 0x4d 0x10 0xb8 0xb7 0x52 0x20 0x19 0xc1 0xb8 0x55 0x01 0x30 0xaf 0x90 0xb4 0x34 0x31 0xba 0xd1 
rxed sm command: public key; conn=0 x=0xce 0x9d 0x48 0xbd 0x04 0x03 0x74 0x24 0x60 0x19 0xfa 0x7a 0x08 0xdc 0xd9 0xf8 0xc8 0x57 0x3e 0xf5 0x73 0xa9 0xb3 0x5c 0xae 0xa4 0xa5 0xb6 0x17 0xeb 0x86 0xdf y=0x3f 0xc3 0x51 0x42 0x4b 0xbb 0x01 0x89 0x11 0x06 0xd8 0x64 0xa3 0x2e 0x01 0x30 0x8e 0x10 0x24 0x03 0x78 0x88 0x47 0xf5 0x6d 0xd2 0xf2 0xd1 0xf9 0x5d 0xa7 0xa9 
our pubkey=0xd8 0xe1 0x3b 0x37 0x76 0x23 0x25 0xf3 0xd5 0x24 0x7c 0xd0 0x15 0x53 0x6a 0x24 0xaa 0xcf 0xab 0xfb 0xc7 0x6f 0x00 0x4e 0x72 0x83 0xf6 0x55 0x48 0xe2 0x8e 0xf4 0x62 0xea 0xe0 0xc2 0x28 0x03 0xc3 0x5f 0x5e 0xe5 0x02 0x61 0x58 0xc9 0xa8 0xee 0x68 0x02 0x90 0x99 0x1d 0x4f 0x04 0x56 0xb8 0x62 0xe7 0x5e 0x56 0x5b 0xdf 0x41 
our privkey=0xc0 0x71 0xa1 0xcc 0xf5 0xa0 0x91 0x11 0xc3 0x37 0x3d 0xac 0xc8 0x4d 0x10 0xb8 0xb7 0x52 0x20 0x19 0xc1 0xb8 0x55 0x01 0x30 0xaf 0x90 0xb4 0x34 0x31 0xba 0xd1 
host tx hci data; handle=0 length=69
ble_hs_hci_acl_tx(): 0x00 0x00 0x45 0x00 0x41 0x00 0x06 0x00 0x0c 0xd8 0xe1 0x3b 0x37 0x76 0x23 0x25 0xf3 0xd5 0x24 0x7c 0xd0 0x15 0x53 0x6a 0x24 0xaa 0xcf 0xab 0xfb 0xc7 0x6f 0x00 0x4e 0x72 0x83 0xf6 0x55 0x48 0xe2 0x8e 0xf4 0x62 0xea 0xe0 0xc2 0x28 0x03 0xc3 0x5f 0x5e 0xe5 0x02 0x61 0x58 0xc9 0xa8 0xee 0x68 0x02 0x90 0x99 0x1d 0x4f 0x04 0x56 0xb8 0x62 0xe7 0x5e 0x56 0x5b 0xdf 0x41 
ble_sm_alg_f4()
    u=0xd8 0xe1 0x3b 0x37 0x76 0x23 0x25 0xf3 0xd5 0x24 0x7c 0xd0 0x15 0x53 0x6a 0x24 0xaa 0xcf 0xab 0xfb 0xc7 0x6f 0x00 0x4e 0x72 0x83 0xf6 0x55 0x48 0xe2 0x8e 0xf4 
    v=0xce 0x9d 0x48 0xbd 0x04 0x03 0x74 0x24 0x60 0x19 0xfa 0x7a 0x08 0xdc 0xd9 0xf8 0xc8 0x57 0x3e 0xf5 0x73 0xa9 0xb3 0x5c 0xae 0xa4 0xa5 0xb6 0x17 0xeb 0x86 0xdf 
    x=0x04 0x51 0x68 0x4b 0x83 0x4f 0xdd 0xda 0xc0 0xc6 0x09 0xdb 0x7d 0x42 0x77 0x7b 
    z=0x00
    out_enc_data=0xd5 0xdd 0xcc 0x65 0xcd 0xb9 0x77 0x7f 0xe1 0x91 0x21 0xb4 0xd1 0x4c 0x2c 0x5b 
host tx hci data; handle=0 length=21
ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x03 0xd5 0xdd 0xcc 0x65 0xcd 0xb9 0x77 0x7f 0xe1 0x91 0x21 0xb4 0xd1 0x4c 0x2c 0x5b 
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 0xeb 0xe9 0x5c 0xae 0x68 0x59 0x3b 0xe6 0xc8 0x11 0x90 0xee 0xa9 0x36 0xab 0xce 
rxed sm command: random; conn=0 value=0xeb 0xe9 0x5c 0xae 0x68 0x59 0x3b 0xe6 0xc8 0x11 0x90 0xee 0xa9 0x36 0xab 0xce 
*** RPA *** enabled
ble_sm_alg_f5()
    w=0x7d 0x0a 0xb1 0x35 0x2e 0x28 0x59 0x99 0xe1 0x1a 0xa3 0x81 0x6c 0x15 0xa9 0x47 0x78 0x8e 0xa8 0x0a 0x3c 0x12 0xff 0x74 0x0d 0xd4 0xdb 0x56 0x56 0x03 0x2f 0x83 
    n1=0xeb 0xe9 0x5c 0xae 0x68 0x59 0x3b 0xe6 0xc8 0x11 0x90 0xee 0xa9 0x36 0xab 0xce 
    n2=0x04 0x51 0x68 0x4b 0x83 0x4f 0xdd 0xda 0xc0 0xc6 0x09 0xdb 0x7d 0x42 0x77 0x7b 
    t=0x93 0xbd 0x8d 0x1f 0x0d 0x22 0xb8 0xda 0xe2 0x2c 0x39 0xc1 0xf1 0xdd 0x7e 0xb6 
    mackey=0xea 0xa2 0x79 0x70 0x25 0x04 0x46 0x2a 0xc8 0x2d 0xf4 0xc3 0xf0 0x2c 0x47 0xfa 
    ltk=0xd2 0xc6 0x01 0x37 0xd6 0x79 0x11 0x3f 0xcc 0xcc 0xa0 0x7f 0x51 0xf8 0x20 0xa1 
host tx hci data; handle=0 length=21
ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x04 0x04 0x51 0x68 0x4b 0x83 0x4f 0xdd 0xda 0xc0 0xc6 0x09 0xdb 0x7d 0x42 0x77 0x7b 
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 0x80 0x6b 0x86 0x1a 0x04 0x39 0xd6 0x48 0x07 0xf4 0x64 0x42 0x22 0x46 0x3c 0xf3 
rxed sm command: dhkey check; conn=0 value=0x80 0x6b 0x86 0x1a 0x04 0x39 0xd6 0x48 0x07 0xf4 0x64 0x42 0x22 0x46 0x3c 0xf3 
*** RPA *** enabled
tk=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 
ble_sm_alg_f6()
    w=0xfa 0x47 0x2c 0xf0 0xc3 0xf4 0x2d 0xc8 0x2a 0x46 0x04 0x25 0x70 0x79 0xa2 0xea 
    n1=0xeb 0xe9 0x5c 0xae 0x68 0x59 0x3b 0xe6 0xc8 0x11 0x90 0xee 0xa9 0x36 0xab 0xce 
    n2=0x04 0x51 0x68 0x4b 0x83 0x4f 0xdd 0xda 0xc0 0xc6 0x09 0xdb 0x7d 0x42 0x77 0x7b 
    r=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 
    iocap=0x04 0x00 0x2d 
    a1t=0x01 
    a1=0xaf 0x88 0x8e 0x61 0x0e 0x42 
    a2t=0x01 
    a2=0x04 0xfc 0xa6 0x28 0xef 0x5a 
    res=0xf3 0x3c 0x46 0x22 0x42 0x64 0xf4 0x07 0x48 0xd6 0x39 0x04 0x1a 0x86 0x6b 0x80 
*** RPA *** enabled
ble_sm_alg_f6()
    w=0xfa 0x47 0x2c 0xf0 0xc3 0xf4 0x2d 0xc8 0x2a 0x46 0x04 0x25 0x70 0x79 0xa2 0xea 
    n1=0x04 0x51 0x68 0x4b 0x83 0x4f 0xdd 0xda 0xc0 0xc6 0x09 0xdb 0x7d 0x42 0x77 0x7b 
    n2=0xeb 0xe9 0x5c 0xae 0x68 0x59 0x3b 0xe6 0xc8 0x11 0x90 0xee 0xa9 0x36 0xab 0xce 
    r=0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 
    iocap=0x03 0x00 0x0d 
    a1t=0x01 
    a1=0x04 0xfc 0xa6 0x28 0xef 0x5a 
    a2t=0x01 
    a2=0xaf 0x88 0x8e 0x61 0x0e 0x42 
    res=0xd8 0xc1 0xaa 0xf5 0xb9 0x08 0xac 0x8e 0x6c 0x30 0x33 0x9a 0xfd 0x3f 0x73 0x36 
host tx hci data; handle=0 length=21
ble_hs_hci_acl_tx(): 0x00 0x00 0x15 0x00 0x11 0x00 0x06 0x00 0x0d 0x36 0x73 0x3f 0xfd 0x9a 0x33 0x30 0x6c 0x8e 0xac 0x08 0xb9 0xf5 0xaa 0xc1 0xd8 
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 0xa1 0x20 0xf8 0x51 0x7f 0xa0 0xcc 0xcc 0x3f 0x11 0x79 0xd6 0x37 0x01 0xc6 0xd2 
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 *** RPA *** enabled
*** RPA *** enabled
handle=0 our_ota_addr_type=1 our_ota_addr=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=1 authenticated=0 bonded=1

D (45736) nvs: nvs_open_from_partition nimble_bond 1
D (45746) nvs: nvs_get_str_or_blob cccd_sec_1
D (45746) nvs: nvs_close 20
D (45756) nvs: nvs_open_from_partition nimble_bond 1
D (45756) nvs: nvs_get_str_or_blob cccd_sec_2
D (45756) nvs: nvs_close 21
D (45766) nvs: nvs_open_from_partition nimble_bond 1
D (45766) nvs: nvs_get_str_or_blob cccd_sec_3
D (45776) nvs: nvs_close 22
D (45776) nvs: nvs_open_from_partition nimble_bond 1
D (45776) nvs: nvs_get_str_or_blob cccd_sec_4
D (45786) nvs: nvs_close 23
D (45786) nvs: nvs_open_from_partition nimble_bond 1
D (45796) nvs: nvs_get_str_or_blob cccd_sec_5
D (45796) nvs: nvs_close 24
D (45796) nvs: nvs_open_from_partition nimble_bond 1
D (45806) nvs: nvs_get_str_or_blob cccd_sec_6
D (45806) nvs: nvs_close 25
D (45816) nvs: nvs_open_from_partition nimble_bond 1
D (45816) nvs: nvs_get_str_or_blob cccd_sec_7
D (45826) nvs: nvs_close 26
D (45826) nvs: nvs_open_from_partition nimble_bond 1
D (45826) nvs: nvs_get_str_or_blob cccd_sec_8
D (45836) nvs: nvs_close 27
D (45836) NIMBLE_NVS: Persisting CCCD value in NVS...
D (45846) nvs: nvs_open_from_partition nimble_bond 1
D (45846) nvs: nvs_get_str_or_blob cccd_sec_1
D (45856) nvs: nvs_close 28
D (45856) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 3
D (45856) nvs: nvs_open_from_partition nimble_bond 1
D (45866) nvs: nvs_set_blob cccd_sec_1 16
D (45876) nvs: nvs_close 29
I (45876) NimBLE: $$$ ble_store_config_write: obj type CCD (rc = 0)
persisting our sec; ediv=0 rand=0 authenticated=0 ltk=0xa1 0x20 0xf8 0x51 0x7f 0xa0 0xcc 0xcc 0x3f 0x11 0x79 0xd6 0x37 0x01 0xc6 0xd2  
D (45896) nvs: nvs_open_from_partition nimble_bond 1
D (45896) nvs: nvs_get_str_or_blob our_sec_1
D (45906) nvs: nvs_close 30
D (45906) nvs: nvs_open_from_partition nimble_bond 1
D (45906) nvs: nvs_get_str_or_blob our_sec_2
D (45916) nvs: nvs_close 31
D (45916) nvs: nvs_open_from_partition nimble_bond 1
D (45926) nvs: nvs_get_str_or_blob our_sec_3
D (45926) nvs: nvs_close 32
D (45926) NIMBLE_NVS: Persisting our sec value to NVS...
D (45936) nvs: nvs_open_from_partition nimble_bond 1
D (45936) nvs: nvs_get_str_or_blob our_sec_1
D (45946) nvs: nvs_close 33
D (45946) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 1
D (45956) nvs: nvs_open_from_partition nimble_bond 1
D (45956) nvs: nvs_set_blob our_sec_1 80
D (45966) nvs: nvs_close 34
I (45966) NimBLE: $$$ ble_store_config_write: obj type OUR SEC (rc = 0)
persisting peer sec; ediv=0 rand=0 authenticated=0 ltk=0xa1 0x20 0xf8 0x51 0x7f 0xa0 0xcc 0xcc 0x3f 0x11 0x79 0xd6 0x37 0x01 0xc6 0xd2  
D (45986) nvs: nvs_open_from_partition nimble_bond 1
D (45986) nvs: nvs_get_str_or_blob peer_sec_1
D (45996) nvs: nvs_close 35
D (45996) nvs: nvs_open_from_partition nimble_bond 1
D (45996) nvs: nvs_get_str_or_blob peer_sec_2
D (46006) nvs: nvs_close 36
D (46006) nvs: nvs_open_from_partition nimble_bond 1
D (46016) nvs: nvs_get_str_or_blob peer_sec_3
D (46016) nvs: nvs_close 37
D (46016) NIMBLE_NVS: Persisting peer sec value in NVS...
D (46026) nvs: nvs_open_from_partition nimble_bond 1
D (46026) nvs: nvs_get_str_or_blob peer_sec_1
D (46036) nvs: nvs_close 38
D (46036) NIMBLE_NVS: Empty NVS index found = 1 for obj_type = 2
D (46046) nvs: nvs_open_from_partition nimble_bond 1
D (46046) nvs: nvs_set_blob peer_sec_1 80
D (46056) nvs: nvs_close 39
I (46056) NimBLE: $$$ ble_store_config_write: obj type PEER SEC (rc = 0)
I (46066) NimBLE: !!! ble_store_write_peer_sec: ble_store_persist_sec() rc = 0
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
W (46086) NimBLE: !!! ble_att_svr_check_perms: handle_id 12:... 
I (46086) NimBLE: !!! ble_att_svr_check_perms: No error
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
W (46256) NimBLE: !!! ble_att_svr_check_perms: handle_id 14:... 
I (46266) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
W (47846) NimBLE: !!! ble_att_svr_check_perms: handle_id 3:... 
I (47856) NimBLE: !!! ble_att_svr_check_perms: early bail - no authen/enc/athor
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
looking up peer sec; 
I (50346) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 0)
looking up peer sec; 
I (50346) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)

>>> Store peer count = 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
W (57896) NimBLE: !!! ble_att_svr_check_perms: handle_id 12:... 
I (57906) NimBLE: !!! ble_att_svr_check_perms: No error
host tx hci data; handle=0 length=9
ble_hs_hci_acl_tx(): 0x00 0x00 0x09 0x00 0x05 0x00 0x04 0x00 0x0b 0xcf 0x8c 0xb1 0x40 
Number of Completed Packets: num_handles=1
handle:0 pkts:1
Disconnection Complete: status=0 handle=0 reason=19
*** RPA *** enabled
*** RPA *** enabled
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=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af 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=1 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 0x01 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; 
I (77166) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 0)
looking up peer sec; 
I (77166) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)

>>> Store peer count = 1
LE connection complete. handle=0 role=1 paddrtype=1 addr=42.e.61.8e.88.af 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
*** RPA *** enabled
0xaf 0x88 0x8e 0x61 0x0e 0x42 RPA NOT resolvable; remove peer rec at index = 0 
 RPA: removed device at index = 0, no. of peer records = 0
D (81096) nvs: nvs_open_from_partition nimble_bond 1
D (81096) nvs: nvs_get_str_or_blob p_dev_rec_1
D (81106) nvs: nvs_close 40
D (81106) nvs: nvs_open_from_partition nimble_bond 1
D (81106) nvs: nvs_get_str_or_blob p_dev_rec_2
D (81116) nvs: nvs_close 41
D (81116) nvs: nvs_open_from_partition nimble_bond 1
D (81126) nvs: nvs_get_str_or_blob p_dev_rec_3
D (81126) nvs: nvs_close 42
D (81126) nvs: nvs_open_from_partition nimble_bond 1
D (81136) nvs: nvs_get_str_or_blob p_dev_rec_4
D (81136) nvs: nvs_close 43
connection established; status=0 *** RPA *** enabled
*** RPA *** enabled
handle=0 our_ota_addr_type=1 our_ota_addr=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af 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 
LE LTK Req. handle=0 rand=00 encdiv=0
*** RPA *** enabled
looking up our sec; peer_addr_type=1 peer_addr=0xaf 0x88 0x8e 0x61 0x0e 0x42  ediv=0x00 rand=0x0 
I (83186) NimBLE: $$$ ble_store_config_read: obj type OUR SEC (rc = 0)
ble_hs_hci_cmd_send: ogf=0x08 ocf=0x001a len=18
0x1a 0x20 0x12 0x00 0x00 0xa1 0x20 0xf8 0x51 0x7f 0xa0 0xcc 0xcc 0x3f 0x11 0x79 0xd6 0x37 0x01 0xc6 0xd2 
Command complete: cmd_pkts=4 ogf=0x8 ocf=0x1a status=0 handle=0
*** RPA *** enabled
*** RPA *** enabled
encryption change event; status=7 *** RPA *** enabled
*** RPA *** enabled
handle=0 our_ota_addr_type=1 our_ota_addr=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=0 authenticated=0 bonded=0

disconnect; reason=19 handle=0 our_ota_addr_type=1 our_ota_addr=5a:ef:28:a6:fc:04 our_id_addr_type=1 our_id_addr=5a:ef:28:a6:fc:04 peer_ota_addr_type=1 peer_ota_addr=42:0e:61:8e:88:af peer_id_addr_type=1 peer_id_addr=42:0e:61:8e:88:af conn_itvl=24 conn_latency=0 supervision_timeout=72 encrypted=0 authenticated=0 bonded=0

error setting advertisement data; rc=22
Resetting state; reason=19
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=4c:11:ae:c9:53:de
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; 
I (83436) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 0)
looking up peer sec; 
I (83446) NimBLE: $$$ ble_store_config_read: obj type PEER SEC (rc = 5)
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=0xe34612be16bc855d
ble_hs_hci_cmd_send: ogf=0x08 ocf=0x0005 len=6
0x05 0x20 0x06 0x62 0x6b 0x1c 0xe3 0x46 0x52 
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=1 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 0x01 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 
Encrypt change: status=0 handle=0 state=1