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:6964
load:0x40078000,len:14724
ho 0 tail 12 room 4
load:0x40080400,len:4292
entry 0x400806e4
I (72) boot: Chip Revision: 1
I (73) boot_comm: chip revision: 1, min. bootloader chip revision: 0
I (41) boot: ESP-IDF v4.0 2nd stage bootloader
I (41) boot: compile time 15:51:41
I (42) boot: Enabling RNG early entropy source...
I (46) boot: SPI Speed      : 40MHz
I (50) boot: SPI Mode       : DIO
I (54) boot: SPI Flash Size : 4MB
I (58) boot: Partition Table:
I (62) boot: ## Label            Usage          Type ST Offset   Length
I (69) boot:  0 nvs              WiFi data        01 02 00009000 00006000
I (76) boot:  1 phy_init         RF data          01 01 0000f000 00001000
I (84) boot:  2 factory          factory app      00 00 00010000 00100000
I (91) boot: End of partition table
I (95) boot_comm: chip revision: 1, min. application chip revision: 0
I (103) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x27858 (161880) map
I (169) esp_image: segment 1: paddr=0x00037880 vaddr=0x3ffbdb60 size=0x02d10 ( 11536) load
I (174) esp_image: segment 2: paddr=0x0003a598 vaddr=0x40080000 size=0x00400 (  1024) load
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

I (176) esp_image: segment 3: paddr=0x0003a9a0 vaddr=0x40080400 size=0x05670 ( 22128) load
I (194) esp_image: segment 4: paddr=0x00040018 vaddr=0x400d0018 size=0x7972c (497452) map
0x400d0018: _stext at ??:?

I (371) esp_image: segment 5: paddr=0x000b974c vaddr=0x40085a70 size=0x0d0dc ( 53468) load
0x40085a70: spi_flash_translate_rc at home/esp/esp-idf-v4.0/components/spi_flash/esp_flash_api.c:687

I (405) boot: Loaded app from partition at offset 0x10000
I (405) boot: Disabling RNG early entropy source...
I (405) cpu_start: Pro cpu up.
I (409) cpu_start: Application information:
I (414) cpu_start: Project name:     a2dp_source
I (419) cpu_start: App version:      045f7d1-dirty
I (424) cpu_start: Compile time:     Mar 25 2020 15:51:41
I (430) cpu_start: ELF file SHA256:  f88ac24264010e5f...
I (436) cpu_start: ESP-IDF:          v4.0
I (441) cpu_start: Starting app cpu, entry point is 0x400812ec
0x400812ec: call_start_cpu1 at home/esp/esp-idf-v4.0/components/esp32/cpu_start.c:272

I (0) cpu_start: App cpu up.
D (451) memory_layout: Checking 11 reserved memory ranges:
D (457) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (463) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10
D (470) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388
D (476) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20
D (482) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c
D (489) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffcc4b0
D (495) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (502) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (508) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (514) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

D (521) memory_layout: Reserved memory range 0x40080000 - 0x40092b49
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

D (527) memory_layout: Building list of available memory regions:
D (534) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000
D (540) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000
D (547) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28
D (553) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60
D (560) memory_layout: Available memory region 0x3ffcc4b0 - 0x3ffce000
D (566) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
D (573) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
D (580) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
D (586) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
D (593) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
D (599) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
D (606) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
D (613) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
D (619) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
D (626) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
D (632) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
D (639) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
D (646) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
D (652) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
D (659) memory_layout: Available memory region 0x3fffc000 - 0x40000000
D (665) memory_layout: Available memory region 0x40092b4c - 0x40094000
D (672) memory_layout: Available memory region 0x40094000 - 0x40096000
D (679) memory_layout: Available memory region 0x40096000 - 0x40098000
D (685) memory_layout: Available memory region 0x40098000 - 0x4009a000
D (692) memory_layout: Available memory region 0x4009a000 - 0x4009c000
D (698) memory_layout: Available memory region 0x4009c000 - 0x4009e000
D (705) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (712) heap_init: Initializing. RAM available for dynamic allocation:
D (719) heap_init: New heap initialised at 0x3ffaff10
I (724) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
D (730) heap_init: New heap initialised at 0x3ffb6388
I (735) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
D (741) heap_init: New heap initialised at 0x3ffb9a20
I (746) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (753) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM
D (759) heap_init: New heap initialised at 0x3ffcc4b0
I (764) heap_init: At 3FFCC4B0 len 00013B50 (78 KiB): DRAM
I (770) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (776) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (783) heap_init: New heap initialised at 0x40092b4c
I (788) heap_init: At 40092B4C len 0000D4B4 (53 KiB): IRAM
I (794) cpu_start: Pro cpu start user code
D (806) clk: RTC_SLOW_CLK calibration value: 3150528
D (816) intr_alloc: Connected src 46 to int 2 (cpu 0)
D (816) intr_alloc: Connected src 57 to int 3 (cpu 0)
D (817) intr_alloc: Connected src 24 to int 9 (cpu 0)
D (821) FLASH_HAL: extra_dummy: 1
D (825) spi_flash: trying chip: issi
D (828) spi_flash: trying chip: gd
D (832) spi_flash: trying chip: generic
I (836) spi_flash: detected chip: generic
I (841) spi_flash: flash io: dio
D (844) chip_generic: set_io_mode: status before 0x0
I (850) 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 (865) heap_init: New heap initialised at 0x3ffe0440
D (875) heap_init: New heap initialised at 0x3ffe4350
D (885) intr_alloc: Connected src 16 to int 12 (cpu 0)
D (885) partition: Loading the partition table
D (895) nvs: nvs_flash_init_custom partition=nvs start=9 count=6
D (925) BTDM_INIT: Release DRAM [0x3ffb09a8] - [0x3ffb1ddc]
I (925) BTDM_INIT: BT controller compile version [79dd6f1]
D (925) BTDM_INIT: .data initialise [0x3ffae6e0] <== [0x4000d890]
D (935) BTDM_INIT: .bss initialise [0x3ffb0000] - [0x3ffb09a8]
D (935) BTDM_INIT: .bss initialise [0x3ffb1ddc] - [0x3ffb2730]
D (945) BTDM_INIT: .bss initialise [0x3ffb2730] - [0x3ffb6388]
D (955) BTDM_INIT: .bss initialise [0x3ffb8000] - [0x3ffb9a20]
D (955) BTDM_INIT: .bss initialise [0x3ffbdb28] - [0x3ffbdb5c]
I (965) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (975) efuse: coding scheme 0
D (975) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (985) efuse: coding scheme 0
D (985) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (995) efuse: coding scheme 0
D (995) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1005) efuse: coding scheme 0
D (1005) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1015) efuse: coding scheme 0
D (1015) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1025) efuse: coding scheme 0
D (1025) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1035) efuse: coding scheme 0
D (1035) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1045) phy_init: loading PHY init data from application binary
D (1045) nvs: nvs_open_from_partition phy 0
D (1055) nvs: nvs_get cal_version 4
D (1055) nvs: nvs_get_str_or_blob cal_mac
D (1065) efuse: coding scheme 0
D (1065) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1075) efuse: coding scheme 0
D (1075) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1085) efuse: coding scheme 0
D (1085) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1095) efuse: coding scheme 0
D (1095) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1105) efuse: coding scheme 0
D (1105) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1115) efuse: coding scheme 0
D (1115) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1125) efuse: coding scheme 0
D (1125) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1135) nvs: nvs_get_str_or_blob cal_data
D (1145) nvs: nvs_close 1
D (1145) efuse: coding scheme 0
D (1145) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1155) efuse: coding scheme 0
D (1155) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1165) efuse: coding scheme 0
D (1165) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1175) efuse: coding scheme 0
D (1175) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1185) efuse: coding scheme 0
D (1185) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1195) efuse: coding scheme 0
D (1195) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1205) efuse: coding scheme 0
D (1205) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (1295) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0
D (1775) nvs: nvs_open_from_partition bt_config.conf 1
D (1775) nvs: nvs_get_str_or_blob bt_cfg_key0
D (1775) nvs: nvs_get_str_or_blob bt_cfg_key0
D (1785) nvs: nvs_close 2
D (1785) nvs: nvs_open_from_partition bt_config.conf 1
D (1785) nvs: nvs_set_blob bt_cfg_key0 406
D (1795) nvs: nvs_close 3
D (1875) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 0
D (1875) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
D (1875) BT_AV: bt_av_hdl_stack_evt evt 0
I (1875) BT_AV: Starting device discovery...
W (1885) BT_BTC: btc_avrc_tg_set_rn_supported_evt failed: AVRC TG not yet initialized

I (1915) BT_AV: event: 10
I (1915) BT_AV: event: 10
I (1925) BT_AV: event: 10
I (1925) BT_AV: event: 10
I (1925) BT_AV: Discovery started.
D (11885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (11885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (11885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (14735) BT_AV: Device discovery failed, continue to discover...
I (14735) BT_AV: Device discovery failed, continue to discover...
I (14735) BT_AV: Discovery started.
D (21885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (21885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (21885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (27555) BT_AV: Device discovery failed, continue to discover...
I (27555) BT_AV: Device discovery failed, continue to discover...
I (27565) BT_AV: Discovery started.
D (31885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (31885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (31885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (40385) BT_AV: Device discovery failed, continue to discover...
I (40385) BT_AV: Device discovery failed, continue to discover...
I (40385) BT_AV: Discovery started.
D (41885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (41885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (41885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
D (51885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (51885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (51885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (53205) BT_AV: Device discovery failed, continue to discover...
I (53205) BT_AV: Device discovery failed, continue to discover...
I (53215) BT_AV: Discovery started.
D (61885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (61885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (61885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (66025) BT_AV: Device discovery failed, continue to discover...
I (66025) BT_AV: Device discovery failed, continue to discover...
I (66035) BT_AV: Discovery started.
D (71885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (71885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (71885) BT_AV: bt_app_av_sm_hdlr state 1, evt 0xff00
I (75725) BT_AV: Scanned device: 0c:ee:99:26:2d:81
I (75725) BT_AV: --Class of Device: 0x2c0414
I (75725) BT_AV: --RSSI: -67
I (75725) BT_AV: Found a target device, address 0c:ee:99:26:2d:81, name Echo Dot-4VS
I (75735) BT_AV: Cancel device discovery ...
I (75745) BT_AV: Device discovery stopped.
I (75745) BT_AV: a2dp connecting to peer: Echo Dot-4VS
E (75755) BT_APPL: reset flags
D (75755) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (75765) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (75765) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
E (77305) BT_APPL: bta_av_rc_create ACP handle exist for shdl:0
W (77505) BT_APPL: new conn_srvc id:18, app_id:0
D (77505) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (77505) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (77515) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
I (77515) BT_AV: a2dp connected
D (81885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (81885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (81885) BT_AV: bt_app_av_sm_hdlr state 5, evt 0xff00
I (81885) BT_AV: a2dp media ready checking ...
D (81895) BT_APP_CORE: bt_app_work_dispatch event 0x3, param len 16
D (81895) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x3
I (81905) BT_AV: bt_app_av_sm_hdlr state 5, evt 0x3
I (81905) BT_AV: a2dp media ready, starting ...
W (81915) BT_APPL: new conn_srvc id:18, app_id:1
D (87765) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 12
D (87765) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
D (87765) RCCT: bt_av_hdl_avrc_ct_evt evt 0
I (87765) RCCT: AVRC conn_state evt: state 1, [0c:ee:99:26:2d:81]
D (91885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (91885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (91885) BT_AV: bt_app_av_sm_hdlr state 5, evt 0xff00
D (96925) BT_APP_CORE: bt_app_work_dispatch event 0x3, param len 16
D (96925) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x3
I (96925) BT_AV: bt_app_av_sm_hdlr state 5, evt 0x3
I (96935) BT_AV: a2dp media start failed.
D (101885) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (101885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (101885) BT_AV: bt_app_av_sm_hdlr state 5, evt 0xff00
I (101885) BT_AV: a2dp media ready checking ...
D (101895) BT_APP_CORE: bt_app_work_dispatch event 0x3, param len 16
D (101895) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x3
I (101905) BT_AV: bt_app_av_sm_hdlr state 5, evt 0x3
I (101905) BT_AV: a2dp media ready, starting ...
assertion "a2dp_source_local_param.btc_aa_src_cb.media_alarm == NULL" failed: file "home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_source.c", line 1496, function: btc_a2dp_source_aa_start_tx
abort() was called at PC 0x400dd357 on core 0
0x400dd357: __assert_func at /builds/idf/crosstool-NG/.build/xtensa-esp32-elf/src/newlib/newlib/libc/stdlib/assert.c:62 (discriminator 8)


ELF file SHA256: f88ac24264010e5fe248487cedcf6a632ea46729fbab1d6b9c5ac22a271e86f6

Backtrace: 0x4008ddad:0x3ffcea70 0x4008e125:0x3ffcea90 0x400dd357:0x3ffceab0 0x4011ba9a:0x3ffceae0 0x4011c51f:0x3ffceb10 0x4011c725:0x3ffceb30 0x400f4e43:0x3ffceb50 0x400f505e:0x3ffceb70 0x400f4d7a:0x3ffceb90 0x400f0dd9:0x3ffcebc0 0x4010e88d:0x3ffcebe0 0x400902b5:0x3ffcec00
0x4008ddad: invoke_abort at home/esp/esp-idf-v4.0/components/esp32/panic.c:155

0x4008e125: abort at home/esp/esp-idf-v4.0/components/esp32/panic.c:172

0x400dd357: __assert_func at /builds/idf/crosstool-NG/.build/xtensa-esp32-elf/src/newlib/newlib/libc/stdlib/assert.c:62 (discriminator 8)

0x4011ba9a: btc_a2dp_source_aa_start_tx at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_source.c:1496 (discriminator 1)

0x4011c51f: btc_a2dp_source_ctrl at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_source.c:249

0x4011c725: btc_a2dp_source_start_audio_req at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_source.c:501

0x400f4e43: btc_a2dp_datapath_open at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_control.c:80

0x400f505e: btc_a2dp_control_datapath_ctrl at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_a2dp_control.c:206

0x400f4d7a: btc_a2dp_call_handler at home/esp/esp-idf-v4.0/components/bt/host/bluedroid/btc/profile/std/a2dp/btc_av.c:1450

0x400f0dd9: btc_thread_handler at home/esp/esp-idf-v4.0/components/bt/common/btc/core/btc_task.c:149

0x4010e88d: osi_thread_run at home/esp/esp-idf-v4.0/components/bt/common/osi/thread.c:68

0x400902b5: vPortTaskWrapper at home/esp/esp-idf-v4.0/components/freertos/port.c:143


Rebooting...
ets Jun  8 2016 00:22:57

rst:0xc (SW_CPU_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:6964
load:0x40078000,len:14724
ho 0 tail 12 room 4
load:0x40080400,len:4292
entry 0x400806e4
I (72) boot: Chip Revision: 1
I (73) boot_comm: chip revision: 1, min. bootloader chip revision: 0
I (41) boot: ESP-IDF v4.0 2nd stage bootloader
I (41) boot: compile time 15:51:41
I (41) boot: Enabling RNG early entropy source...
I (46) boot: SPI Speed      : 40MHz
I (50) boot: SPI Mode       : DIO
I (54) boot: SPI Flash Size : 4MB
I (58) boot: Partition Table:
I (62) boot: ## Label            Usage          Type ST Offset   Length
I (69) boot:  0 nvs              WiFi data        01 02 00009000 00006000
I (76) boot:  1 phy_init         RF data          01 01 0000f000 00001000
I (84) boot:  2 factory          factory app      00 00 00010000 00100000
I (91) boot: End of partition table
I (96) boot_comm: chip revision: 1, min. application chip revision: 0
I (103) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x27858 (161880) mapI (169) esp_image: segment 1: paddr=0x00037880 vaddr=0x3ffbdb60 size=0x02d10 ( 11536) load
I (174) esp_image: segment 2: paddr=0x0003a598 vaddr=0x40080000 size=0x00400 (  1024) load
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

I (176) esp_image: segment 3: paddr=0x0003a9a0 vaddr=0x40080400 size=0x05670 ( 22128) load
I (194) esp_image: segment 4: paddr=0x00040018 vaddr=0x400d0018 size=0x7972c (497452) map
0x400d0018: _stext at ??:?

I (371) esp_image: segment 5: paddr=0x000b974c vaddr=0x40085a70 size=0x0d0dc ( 53468) load
0x40085a70: spi_flash_translate_rc at home/esp/esp-idf-v4.0/components/spi_flash/esp_flash_api.c:687

I (405) boot: Loaded app from partition at offset 0x10000
I (405) boot: Disabling RNG early entropy source...
I (405) cpu_start: Pro cpu up.
I (409) cpu_start: Application information:
I (414) cpu_start: Project name:     a2dp_source
I (419) cpu_start: App version:      045f7d1-dirty
I (424) cpu_start: Compile time:     Mar 25 2020 15:51:41
I (430) cpu_start: ELF file SHA256:  f88ac24264010e5f...
I (436) cpu_start: ESP-IDF:          v4.0
I (441) cpu_start: Starting app cpu, entry point is 0x400812ec
0x400812ec: call_start_cpu1 at home/esp/esp-idf-v4.0/components/esp32/cpu_start.c:272

I (427) cpu_start: App cpu up.
D (452) memory_layout: Checking 11 reserved memory ranges:
D (457) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (463) memory_layout: Reserved memory range 0x3ffae6e0 - 0x3ffaff10
D (470) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb6388
D (476) memory_layout: Reserved memory range 0x3ffb8000 - 0x3ffb9a20
D (483) memory_layout: Reserved memory range 0x3ffbdb28 - 0x3ffbdb5c
D (489) memory_layout: Reserved memory range 0x3ffbdb60 - 0x3ffcc4b0
D (495) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (502) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (508) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (515) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

D (521) memory_layout: Reserved memory range 0x40080000 - 0x40092b49
0x40080000: _WindowOverflow4 at home/esp/esp-idf-v4.0/components/freertos/xtensa_vectors.S:1778

D (528) memory_layout: Building list of available memory regions:
D (534) memory_layout: Available memory region 0x3ffaff10 - 0x3ffb0000
D (540) memory_layout: Available memory region 0x3ffb6388 - 0x3ffb8000
D (547) memory_layout: Available memory region 0x3ffb9a20 - 0x3ffbdb28
D (554) memory_layout: Available memory region 0x3ffbdb5c - 0x3ffbdb60
D (560) memory_layout: Available memory region 0x3ffcc4b0 - 0x3ffce000
D (567) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
D (573) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
D (580) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
D (587) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
D (593) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
D (600) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
D (606) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
D (613) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
D (620) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
D (626) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
D (633) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
D (639) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
D (646) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
D (653) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
D (659) memory_layout: Available memory region 0x3fffc000 - 0x40000000
D (666) memory_layout: Available memory region 0x40092b4c - 0x40094000
D (672) memory_layout: Available memory region 0x40094000 - 0x40096000
D (679) memory_layout: Available memory region 0x40096000 - 0x40098000
D (686) memory_layout: Available memory region 0x40098000 - 0x4009a000
D (692) memory_layout: Available memory region 0x4009a000 - 0x4009c000
D (699) memory_layout: Available memory region 0x4009c000 - 0x4009e000
D (705) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (712) heap_init: Initializing. RAM available for dynamic allocation:
D (719) heap_init: New heap initialised at 0x3ffaff10
I (724) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
D (730) heap_init: New heap initialised at 0x3ffb6388
I (735) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
D (742) heap_init: New heap initialised at 0x3ffb9a20
I (747) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (753) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM
D (759) heap_init: New heap initialised at 0x3ffcc4b0
I (764) heap_init: At 3FFCC4B0 len 00013B50 (78 KiB): DRAM
I (770) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (776) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (783) heap_init: New heap initialised at 0x40092b4c
I (788) heap_init: At 40092B4C len 0000D4B4 (53 KiB): IRAM
I (794) cpu_start: Pro cpu start user code
D (806) clk: RTC_SLOW_CLK calibration value: 3174835
D (816) intr_alloc: Connected src 46 to int 2 (cpu 0)
D (817) intr_alloc: Connected src 57 to int 3 (cpu 0)
D (818) intr_alloc: Connected src 24 to int 9 (cpu 0)
D (822) FLASH_HAL: extra_dummy: 1
D (825) spi_flash: trying chip: issi
D (829) spi_flash: trying chip: gd
D (832) spi_flash: trying chip: generic
I (836) spi_flash: detected chip: generic
I (841) spi_flash: flash io: dio
D (845) chip_generic: set_io_mode: status before 0x0
I (850) 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 (865) heap_init: New heap initialised at 0x3ffe0440
D (875) heap_init: New heap initialised at 0x3ffe4350
D (885) intr_alloc: Connected src 16 to int 12 (cpu 0)
D (885) partition: Loading the partition table
D (895) nvs: nvs_flash_init_custom partition=nvs start=9 count=6
D (925) BTDM_INIT: Release DRAM [0x3ffb09a8] - [0x3ffb1ddc]
I (925) BTDM_INIT: BT controller compile version [79dd6f1]
D (925) BTDM_INIT: .data initialise [0x3ffae6e0] <== [0x4000d890]
D (935) BTDM_INIT: .bss initialise [0x3ffb0000] - [0x3ffb09a8]
D (935) BTDM_INIT: .bss initialise [0x3ffb1ddc] - [0x3ffb2730]
D (945) BTDM_INIT: .bss initialise [0x3ffb2730] - [0x3ffb6388]
D (955) BTDM_INIT: .bss initialise [0x3ffb8000] - [0x3ffb9a20]
D (955) BTDM_INIT: .bss initialise [0x3ffbdb28] - [0x3ffbdb5c]
I (965) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (975) efuse: coding scheme 0
D (975) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (985) efuse: coding scheme 0
D (985) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (995) efuse: coding scheme 0
D (995) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1005) efuse: coding scheme 0
D (1005) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1015) efuse: coding scheme 0
D (1015) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1025) efuse: coding scheme 0
D (1025) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1035) efuse: coding scheme 0
D (1035) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1045) phy_init: loading PHY init data from application binary
D (1045) nvs: nvs_open_from_partition phy 0
D (1055) nvs: nvs_get cal_version 4
D (1055) nvs: nvs_get_str_or_blob cal_mac
D (1065) efuse: coding scheme 0
D (1065) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1075) efuse: coding scheme 0
D (1075) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1085) efuse: coding scheme 0
D (1085) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1095) efuse: coding scheme 0
D (1095) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1105) efuse: coding scheme 0
D (1105) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1115) efuse: coding scheme 0
D (1115) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1125) efuse: coding scheme 0
D (1125) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
D (1135) nvs: nvs_get_str_or_blob cal_data
D (1145) nvs: nvs_close 1
D (1145) efuse: coding scheme 0
D (1145) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 8 bit
D (1155) efuse: coding scheme 0
D (1155) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 0 bit
D (1165) efuse: coding scheme 0
D (1165) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 24 bit
D (1175) efuse: coding scheme 0
D (1175) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 16 bit
D (1185) efuse: coding scheme 0
D (1185) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 8 bit
D (1195) efuse: coding scheme 0
D (1195) efuse: In EFUSE_BLK0__DATA1_REG is used 8 bits starting with 0 bit
D (1205) efuse: coding scheme 0
D (1205) efuse: In EFUSE_BLK0__DATA2_REG is used 8 bits starting with 16 bit
I (1295) phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0
D (1775) nvs: nvs_open_from_partition bt_config.conf 1
D (1775) nvs: nvs_get_str_or_blob bt_cfg_key0
D (1775) nvs: nvs_get_str_or_blob bt_cfg_key0
D (1775) nvs: nvs_close 2
D (1775) nvs: nvs_open_from_partition bt_config.conf 1
D (1785) nvs: nvs_set_blob bt_cfg_key0 406
D (1785) nvs: nvs_close 3
D (1865) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 0
D (1875) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
D (1875) BT_AV: bt_av_hdl_stack_evt evt 0
I (1875) BT_AV: Starting device discovery...
W (1885) BT_BTC: btc_avrc_tg_set_rn_supported_evt failed: AVRC TG not yet initialized

I (1915) BT_AV: event: 10
I (1915) BT_AV: event: 10
I (1915) BT_AV: event: 10
I (1915) BT_AV: event: 10
I (1915) BT_AV: Discovery started.
I (4125) BT_AV: Scanned device: 0c:ee:99:26:2d:81
I (4125) BT_AV: --Class of Device: 0x2c0414
I (4125) BT_AV: --RSSI: -66
I (4125) BT_AV: Found a target device, address 0c:ee:99:26:2d:81, name Echo Dot-4VS
I (4135) BT_AV: Cancel device discovery ...
I (4145) BT_AV: Device discovery stopped.
I (4145) BT_AV: a2dp connecting to peer: Echo Dot-4VS
E (4155) BT_APPL: reset flags
D (4155) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (4165) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (4165) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
W (10495) BT_SDP: SDP - Rcvd conn cnf with error: 0x10  CID 0x40

W (10495) BT_BTC: BTA_AV_OPEN_EVT::FAILED status: 2

D (10495) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (10505) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (10515) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
D (11875) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (11875) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (11875) BT_AV: bt_app_av_sm_hdlr state 3, evt 0xff00
I (11875) BT_AV: a2dp connecting to peer: 0c:ee:99:26:2d:81
D (11885) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (11895) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (11895) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
W (19295) BT_SDP: SDP - Rcvd conn cnf with error: 0x10  CID 0x41

W (19295) BT_BTC: BTA_AV_OPEN_EVT::FAILED status: 2

D (19295) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (19305) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (19305) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
D (21875) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (21875) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (21875) BT_AV: bt_app_av_sm_hdlr state 3, evt 0xff00
I (21875) BT_AV: a2dp connecting to peer: 0c:ee:99:26:2d:81
D (21885) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (21885) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (21895) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
W (28235) BT_SDP: SDP - Rcvd conn cnf with error: 0x10  CID 0x42

W (28235) BT_BTC: BTA_AV_OPEN_EVT::FAILED status: 2

D (28235) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (28245) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (28245) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0
D (31875) BT_APP_CORE: bt_app_work_dispatch event 0xff00, param len 0
D (31875) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0xff00
I (31875) BT_AV: bt_app_av_sm_hdlr state 3, evt 0xff00
I (31875) BT_AV: a2dp connecting to peer: 0c:ee:99:26:2d:81
D (31885) BT_APP_CORE: bt_app_work_dispatch event 0x0, param len 16
D (31895) BT_APP_CORE: bt_app_task_handler, sig 0x1, 0x0
I (31895) BT_AV: bt_app_av_sm_hdlr state 4, evt 0x0

