set_volume does not change the volume until the music is paused (on iPhone iOS >= 15.7.9)

Problem Description
Volume changes are received by the device, but the actual volume doesn't change until the music is paused then played. It doesn't update the volume during streaming.
Based on serial logs, the set_volume inside A2DPVolumeControl doesn't fire when music is playing. Only when you pause the song, will "set_volume" function be fired.
Device Description
ESP32 Wroom https://www.amazon.com/gp/product/B0C7C2HQ7P/ref=ppx_yo_dt_b_search_asin_title?ie=UTF8&psc=1
Sketch

#include "BluetoothA2DPSink.h"
#include "./data/mrhemVolumeControl.h"
BluetoothA2DPSink a2dp_sink;
A2DPMrhemVolumeControl mrhemVolumeControl;
void setup() {

  Serial.begin(9600);
  int mclkPin=3;
  i2s_pin_config_t external_pin_config = {
        .mck_io_num = mclkPin,
        .bck_io_num =33,
        .ws_io_num = 32,
        .data_out_num = 22,
        .data_in_num = I2S_PIN_NO_CHANGE,
    };
  static const i2s_config_t externalDAC_config = {
        .mode = (i2s_mode_t) (I2S_MODE_MASTER | I2S_MODE_TX),
        .sample_rate =  44100, // corrected by info from bluetooth
        .bits_per_sample = (i2s_bits_per_sample_t) 16,
        .channel_format = I2S_CHANNEL_FMT_RIGHT_LEFT,
        .communication_format = (i2s_comm_format_t)I2S_COMM_FORMAT_STAND_MSB,
        .intr_alloc_flags = 0, // default interrupt priority
        .dma_buf_count = 8,
        .dma_buf_len = 64,
        .use_apll = true,
        .tx_desc_auto_clear = true, // avoiding noise in case of data unavailability
        .mclk_multiple=(i2s_mclk_multiple_t) 256,

  };

  a2dp_sink.set_volume_control(&mrhemVolumeControl);

  a2dp_sink.set_avrc_metadata_callback(avrc_metadata_callback);
  bool externalDAC=true;

    a2dp_sink.set_pin_config(external_pin_config);
    a2dp_sink.set_i2s_config(externalDAC_config);
    a2dp_sink.i2s_mclk_pin_select(mclkPin);

  a2dp_sink.start("mrhemMusicBasic");
}

./data/mrhemVolumeControl.h:
#include "BluetoothA2DPSink.h"
#include "SoundData.h"
#include "esp_log.h"

class A2DPMrhemVolumeControl : public A2DPVolumeControl {

        public: A2DPMrhemVolumeControl() {
            volumeFactorMax = 4096;
        }

        virtual void set_volume(uint8_t volume) override {
            constexpr double base = 1.4;
            constexpr double bits = 12;
            constexpr double zero_ofs = pow(base, -bits);
            constexpr double scale = pow(2.0, bits);
            double volumeFactorFloat = (pow(base, volume * bits / 127.0 - bits) - zero_ofs) * scale / (1.0 - zero_ofs);
            volumeFactor = volumeFactorFloat;
            if (volumeFactor > 0x1000) {
                volumeFactor = 0x1000;
            }
            Serial.print("mrhem default Volume is now");
            Serial.println(volumeFactor);
        }
};

Other Steps to Reproduce
I monitored the serial output in the Arduino IDE's Serial Monitor. I press the volume change buttons on the iphone, but "mrhem default Volume is now" does not output. It only outputs much later with the newest value when I pause the stream.
Provide your Version of the EP32 Arduino Core (or the IDF Version)
esp32 by Espressif: 2.0.11
I have checked existing issues, discussions and online documentation

[X] I confirm I have checked existing issues, discussions and online documentation

In the light that this is working perfectly with the provided default implementation, I tend to assume that you messed something up!
What are you doing in the loop ?

The loop is nothing:
int8_t IntStat = 0;
int8_t IntConnected = 0;
int16_t runningMean=0;
int16_t absRunningMean=0;
void loop() {

   if (a2dp_sink.is_connected() != IntConnected) {
    IntConnected = a2dp_sink.is_connected();
    if (IntConnected == 0) {
      Serial.println("Not Connected");
    }
    if (IntConnected == 1) {
      Serial.println("Connected");
    }
  }
  if (a2dp_sink.get_audio_state() != IntStat) {
    IntStat = a2dp_sink.get_audio_state();
    if (IntStat == 0) {
      Serial.println("Stop");
    }
    if (IntStat == 2) {
      Serial.println("Play");
    }
  }

}
// btw, I have commented out the following function & the line in the "setup" function that references it, and problem still persists too:
void avrc_metadata_callback(uint8_t data1, const uint8_t *data2) {
  Serial.printf("AVRC metadata rsp: attribute id 0x%x, %s\n", data1, data2);
}

The reason for providing my own implementation, was only to determine why the volume wasn't working 😆. I have trimmed down to basically just the default implementation.
When I activate the verbose logging, there is a huge delay in the volume being changed. Also, the music is skipping because the controller seems like it is logging too much to also be able to stream correctly. However, the volume does change without me pressing pause, it just is like a 10 second delay.

Did you double check that you are not starving the A2DP task with your loop ?
Just add some delay calls in your loop ....

I thought that was going to fix it but even using void loop() { } did not help...
Here is some of the log from when I press "play" and tap "volume up" about once per second:
16:18:12.065 -> [455564][D][BluetoothA2DPSink.cpp:1435] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
16:18:12.162 -> [455670][D][BluetoothA2DPSink.cpp:570] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
16:18:12.258 -> [455776][D][BluetoothA2DPSink.cpp:1443] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
16:18:12.354 -> [455869][D][BluetoothA2DPSink.cpp:587] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_CHANGE_NOTIFY_EVT
16:18:12.483 -> [455969][I][BluetoothA2DPSink.cpp:1459] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 87%
16:18:12.611 -> [456093][D][BluetoothA2DPSink.cpp:358] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 12
16:18:12.707 -> [456205][I][BluetoothA2DPSink.cpp:1399] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 87
16:18:12.835 -> [456322][D][BluetoothA2DPSink.cpp:390] app_send_msg(): [BT_AV] app_send_msg
16:18:12.931 -> mrhem default Volume is now2433
16:18:12.963 -> [456528][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:13.059 -> [456648][D][BluetoothA2DPSink.cpp:413] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
16:18:13.155 -> [456656][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:13.250 -> [456760][I][BluetoothA2DPSink.cpp:416] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:18:13.379 -> [456870][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:13.476 -> [456976][D][BluetoothA2DPSink.cpp:381] app_work_dispatched(): [BT_AV] app_work_dispatched
16:18:13.572 -> [457072][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:13.668 -> [457167][D][BluetoothA2DPSink.cpp:1435] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
16:18:13.765 -> [457278][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:13.861 -> [457369][D][BluetoothA2DPSink.cpp:1443] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
16:18:13.957 -> [457465][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:14.052 -> [457565][I][BluetoothA2DPSink.cpp:1459] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 93%
16:18:14.181 -> [457677][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:14.277 -> [457773][I][BluetoothA2DPSink.cpp:1399] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 93
16:18:14.405 -> [457869][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:14.500 -> mrhem default Volume is now3159
16:18:14.533 -> [458103][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:14.628 -> [458211][D][BluetoothA2DPSink.cpp:413] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
16:18:14.724 -> [458218][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:14.820 -> [458322][I][BluetoothA2DPSink.cpp:416] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:18:14.948 -> [458433][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:15.044 -> [458539][D][BluetoothA2DPSink.cpp:381] app_work_dispatched(): [BT_AV] app_work_dispatched
16:18:15.140 -> [458635][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:15.237 -> [458729][D][BluetoothA2DPSink.cpp:1435] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
16:18:15.333 -> [458841][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:15.430 -> [458932][D][BluetoothA2DPSink.cpp:1443] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
16:18:15.525 -> [459027][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:15.621 -> [459127][I][BluetoothA2DPSink.cpp:1459] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 100%
16:18:15.749 -> [459238][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:15.845 -> [459337][I][BluetoothA2DPSink.cpp:1399] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 100
16:18:15.941 -> [459433][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:16.037 -> mrhem default Volume is now4096
16:18:16.102 -> [459668][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:16.165 -> [459775][D][BluetoothA2DPSink.cpp:413] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
16:18:16.293 -> [459783][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:16.390 -> [459887][I][BluetoothA2DPSink.cpp:416] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:18:16.487 -> [459997][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback
16:18:16.583 -> [460103][D][BluetoothA2DPSink.cpp:381] app_work_dispatched(): [BT_AV] app_work_dispatched
16:18:16.678 -> [460199][D][A2DPVolumeControl.h:43] update_audio_data(): [VolumeControl] update_audio_data
16:18:16.774 -> [460294][D][BluetoothA2DPSink.cpp:1316] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
16:18:16.903 -> [460405][D][BluetoothA2DPSink.cpp:1116] audio_data_callback(): [BT_AV] audio_data_callback

The volume change is about 10 seconds late

Yes: an empty loop is equally bad!

Right true, I got that from the homepage, but i also just tried void loop(){ delay(100); } and it also did not help...

Did you check the log it you get the events in time from the IPhone ?

Well, I don't know how to check the timing of those events, but it works on my android tablet, also the audio quality is much better..... My iphone is fairly old too (iphone 6S) but it's not surprising knowing Apple that it would make up a problem like this for non-Apple bluetooth.... I will try that class.

The problem persists when using Apple Music (perhaps not related to  https://github.com/pschatzmann/ESP32-A2DP/issues/485 )
The problem persists in iOS 16.6 (iPhone SE)

This issue is likely related to the last few detailed comments in https://github.com/pschatzmann/ESP32-A2DP/issues/493, as the behaviors are quite similar

I committed a new version of BluetoothA2DPSinkQueued which might resolve your issues...
You will need to run your sketch with this class instead of the simple BluetoothA2DPSink!
Since I don't have any recent IPhone, please let me know if this help ?

I will do some more investigation, but I wanted to paste this log after doing what you suggested, in case there is an easy fix.
16:59:08.404 -> ��ѽ�ѡ�): [BT_AV] bluedroid initialized
16:59:08.489 -> [   838][I][BluetoothA2DPSink.cpp:338] init_bluetooth(): [BT_AV] bluedroid enabled
16:59:08.531 -> [   838][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:08.659 -> [   935][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable true
16:59:08.787 -> [   940][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:08.850 -> [  1050][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:08.978 -> [  1131][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:09.075 -> [  1252][I][BluetoothA2DPSink.cpp:643] av_hdl_a2d_evt(): [BT_AV] A2DP PROF STATE: Init Compl
16:59:09.171 ->
16:59:09.171 -> [  1333][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:09.235 -> [  1050][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 117540
16:59:09.330 -> [  1514][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:13.905 -> [  6314][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 16
16:59:14.306 -> [  6699][I][BluetoothA2DPSink.cpp:497] app_gap_callback(): [BT_AV] authentication success: iPhone (16)
16:59:14.402 -> [  6813][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:14.530 -> [  6813][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
16:59:14.657 -> [  6913][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connecting, [c0:b6:58:63:bf:5d]
16:59:14.785 -> [  7049][I][BluetoothA2DPSink.cpp:814] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTING
16:59:14.881 -> [  7164][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:15.009 -> [  7285][I][BluetoothA2DPSink.cpp:661] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , codec type 0
16:59:15.105 -> [  7390][I][BluetoothA2DPSink.cpp:673] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , sample_rate 44100
16:59:15.233 -> [  7501][I][BluetoothA2DPSink.cpp:684] handle_audio_cfg(): [BT_AV] configure audio player 21-15-2-35
16:59:15.330 ->
16:59:15.330 -> [  7609][I][BluetoothA2DPSink.cpp:692] handle_audio_cfg(): [BT_AV] audio player configured, samplerate=44100
16:59:15.457 -> [  7724][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:15.585 -> [  7844][I][BluetoothA2DPSink.cpp:1463] av_hdl_avrc_tg_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
16:59:15.714 -> [  7970][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:15.810 -> [  8091][I][BluetoothA2DPSink.cpp:1479] av_hdl_avrc_tg_evt(): [BT_AV] AVRC register event notification: 13, param: 0x0
16:59:15.938 -> [  8216][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:16.066 -> [  8337][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
16:59:16.195 -> [  8452][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connected, [c0:b6:58:63:bf:5d]
16:59:16.323 -> [  8586][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
16:59:16.419 -> [  8701][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable false
16:59:16.547 -> [  8816][I][BluetoothA2DPSinkQueued.cpp:6] bt_i2s_task_start_up(): [BT_API] ringbuffer data empty! mode changed: RINGBUFFER_MODE_PREFETCHING
16:59:16.706 -> [  8964][I][BluetoothA2DPSinkQueued.cpp:21] bt_i2s_task_start_up(): [BT_AV] BtI2STask Started
16:59:16.803 -> [  9063][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:16.899 -> [  9179][I][BluetoothA2DPSink.cpp:550] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT stat:0
16:59:17.027 -> [  9184][I][BluetoothA2DPSink.cpp:939] av_hdl_avrc_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
16:59:17.155 -> [  9295][I][BluetoothA2DPSink.cpp:552] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT remote name:iPhone (16)
16:59:17.283 -> [  9417][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:17.411 -> [  9667][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
16:59:17.507 -> [  9763][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:17.604 -> [  9884][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
16:59:17.731 -> [ 10000][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:17.859 -> [ 10120][I][BluetoothA2DPSink.cpp:992] av_hdl_avrc_evt(): [BT_AV] remote rn_cap: count 7, bitmask 0x1f06
16:59:17.955 -> [ 10260][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
16:59:18.052 -> [ 10327][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72760
16:59:18.367 -> [ 10783][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:18.496 -> [ 10783][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x1, My Dad's Gone Crazy (feat. Hailie Jade)
16:59:18.656 -> [ 10919][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:18.784 -> [ 11040][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x2, Eminem
16:59:18.880 -> [ 11155][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:19.008 -> [ 11276][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x4,
16:59:19.104 -> [ 11386][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:19.232 -> [ 11506][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x8, 1
16:59:19.360 -> [ 11617][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:19.456 -> [ 11738][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x10, 126
16:59:19.584 -> [ 11851][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:19.712 -> [ 11972][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x20,
16:59:20.395 -> [ 12784][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
16:59:20.459 -> [ 12784][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72760
16:59:23.600 -> [ 16009][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
16:59:23.696 -> [ 16009][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72760
16:59:25.894 -> [ 18284][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
16:59:25.989 -> [ 18284][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72760
16:59:26.054 -> [ 18338][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:26.182 -> [ 18459][I][BluetoothA2DPSink.cpp:712] handle_audio_state(): [BT_AV] A2DP audio state: Started
16:59:26.278 -> [ 18508][I][BluetoothA2DPSinkQueued.cpp:100] write_audio(): [BT_API] ringbuffer data increased! mode changed: RINGBUFFER_MODE_PROCESSING
16:59:26.438 -> [ 18559][I][BluetoothA2DPSink.cpp:724] handle_audio_state(): [BT_AV] i2s_start
16:59:26.502 -> [ 18703][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
16:59:26.630 -> [ 18890][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:26.726 -> [ 18713][W][BluetoothA2DPSinkQueued.cpp:93] write_audio(): [BT_API] ringbuffer overflowed, ready to decrease data! mode changed: RINGBUFFER_MODE_DROPPING
16:59:26.886 -> [ 19079][I][BluetoothA2DPSinkQueued.cpp:62] i2s_task_handler(): [BT_API] ringbuffer underflowed! mode changed: RINGBUFFER_MODE_PREFETCHING
16:59:27.045 -> [ 19175][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:27.174 -> [ 19429][I][BluetoothA2DPSinkQueued.cpp:84] write_audio(): [BT_API] ringbuffer data decreased! mode changed: RINGBUFFER_MODE_PROCESSING
16:59:27.302 -> [ 19596][W][BluetoothA2DPSinkQueued.cpp:93] write_audio(): [BT_API] ringbuffer overflowed, ready to decrease data! mode changed: RINGBUFFER_MODE_DROPPING
16:59:27.462 -> [ 19736][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:27.589 -> [ 19847][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:27.686 -> [ 19959][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:27.782 -> [ 20070][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:27.910 -> [ 20182][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.006 -> [ 20293][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.134 -> [ 20405][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.231 -> [ 20516][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.359 -> [ 20628][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.456 -> [ 20739][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.584 -> [ 20851][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.681 -> [ 20962][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.809 -> [ 21074][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:28.906 -> [ 21185][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.034 -> [ 21296][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.130 -> [ 21408][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.258 -> [ 21519][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.354 -> [ 21631][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.482 -> [ 21743][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.578 -> [ 21854][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.706 -> [ 21965][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.802 -> [ 22077][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:29.930 -> [ 22188][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.026 -> [ 22300][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.154 -> [ 22411][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.250 -> [ 22522][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.345 -> [ 22634][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.474 -> [ 22745][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.569 -> [ 22857][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.699 -> [ 22968][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.794 -> [ 23080][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:30.923 -> [ 23192][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.018 -> [ 23303][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.147 -> [ 23414][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.242 -> [ 23526][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.370 -> [ 23637][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.466 -> [ 23749][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.594 -> [ 23860][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.690 -> [ 23972][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.818 -> [ 24083][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:31.915 -> [ 24195][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
16:59:32.042 -> [ 24306][W][BE (30951) task_wdt: Task watchdog got triggered. The following tasks did not reset the watchdog in time:
16:59:32.170 -> E (30951) task_wdt:  - IDLE (CPU 0)
16:59:32.203 -> E (30951) task_wdt: Tasks currently running:
16:59:32.235 -> E (30951) task_wdt: CPU 0: BTC_TASK
16:59:32.266 -> E (30951) task_wdt: CPU 1: IDLE
16:59:32.331 -> E (30951) task_wdt: AbortingGuru Meditation Error: Core  0 panic'ed (Interrupt wdt timeout on CPU0).
16:59:32.427 ->
16:59:32.427 -> Core  0 register dump:
16:59:32.459 -> PC      : 0x4000921a  PS      : 0x00060035  A0      : 0x80007d16  A1      : 0x3ffbe9ac
16:59:32.556 -> A2      : 0x00800000  A3      : 0x30000000  A4      : 0x00000000  A5      : 0x00000020
16:59:32.652 -> A6      : 0x3ffc68c0  A7      : 0x00000002  A8      : 0x3ff40000  A9      : 0x0000002e
16:59:32.715 -> A10     : 0x00800000  A11     : 0x3ff4001c  A12     : 0x3ffced50  A13     : 0x3ffced30
16:59:32.812 -> A14     : 0x00000000  A15     : 0x3ffced50  SAR     : 0x00000004  EXCCAUSE: 0x00000005
16:59:32.908 -> EXCVADDR: 0x00000000  LBEG    : 0x40090fad  LEND    : 0x40090fbd  LCOUNT  : 0xfffffffe
16:59:33.004 -> Core  0 was running in ISR context:
16:59:33.036 -> EPC1    : 0x400e31cf  EPC2    : 0x00000000  EPC3    : 0x4000921a  EPC4    : 0x4000921f
16:59:33.132 ->
16:59:33.132 ->
16:59:33.132 -> Backtrace: 0x40009217:0x3ffbe9ac |<-CORRUPTED
16:59:33.195 ->
16:59:33.195 ->
16:59:33.195 ->
16:59:33.195 -> Core  1 register dump:
16:59:33.228 -> PC      : 0x40196f2e  PS      : 0x00060835  A0      : 0x800e15�f��E���b����[    34][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 222316
16:59:33.779 -> [    35][I][BluetoothA2DPSink.cpp:172] start(): [BT_AV] Device name will be set to 'mrhemMusicBasic5'
16:59:33.906 -> [   101][I][BluetoothA2DPCommon.cpp:166] get_last_connection(): [BT_AV] nvs_blob does not exist
16:59:34.003 -> [   202][I][BluetoothA2DPSink.cpp:238] init_i2s(): [BT_AV] init_i2s
16:59:34.067 -> [   274][I][BluetoothA2DPSink.cpp:240] init_i2s(): [BT_AV] init_i2s is_i2s_output
16:59:34.163 -> [   461][I][BluetoothA2DPSink.cpp:320] init_bluetooth(): [BT_AV] controller initialized
16:59:34.429 -> [   772][I][BluetoothA2DPSink.cpp:329] init_bluetooth(): [BT_AV] bluedroid initialized
16:59:34.525 -> [   841][I][BluetoothA2DPSink.cpp:338] init_bluetooth(): [BT_AV] bluedroid enabled
16:59:34.620 -> [   841][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:34.716 -> [   938][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable true
16:59:34.845 -> [   942][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:34.909 -> [  1052][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
16:59:35.037 -> [  1133][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:35.133 -> [  1254][I][BluetoothA2DPSink.cpp:643] av_hdl_a2d_evt(): [BT_AV] A2DP PROF STATE: Init Compl
16:59:35.229 ->
16:59:35.229 -> [  1335][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
16:59:35.293 -> [  1052][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 117548
16:59:35.389 -> [  1517][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10

Strange: I tested it on Android and there it was working. It seems that the I2S writes are not working, so the queue is never consumed.
I will compare your log with mine tomorrow...

The last log was using the PCM5102 breakout board from the wiki. I actually am not getting audio from that board. But this is what happens using a different external DAC (NAU8402) where i am getting audio (still i2s_write_data failed).
17:26:53.116 -> [ 43314][I][BluetoothA2DPSink.cpp:692] handle_audio_cfg(): [BT_AV] audio player configured, samplerate=44100
17:26:53.212 -> [ 43428][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
17:26:53.341 -> [ 43549][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
17:26:53.469 -> [ 43664][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connected, [c0:b6:58:63:bf:5d]
17:26:53.596 -> [ 43798][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
17:26:53.693 -> [ 43913][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable false
17:26:53.821 -> [ 44028][I][BluetoothA2DPSinkQueued.cpp:6] bt_i2s_task_start_up(): [BT_API] ringbuffer data empty! mode changed: RINGBUFFER_MODE_PREFETCHING
17:26:53.982 -> [ 44176][I][BluetoothA2DPSinkQueued.cpp:21] bt_i2s_task_start_up(): [BT_AV] BtI2STask Started
17:26:54.078 -> [ 44275][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
17:26:54.174 -> [ 44300][I][BluetoothA2DPSink.cpp:550] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT stat:0
17:26:54.302 -> [ 44396][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
17:26:54.398 -> [ 44507][I][BluetoothA2DPSink.cpp:552] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT remote name:iPhone (16)
17:26:54.527 -> [ 44603][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
17:26:54.655 -> [ 44741][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
17:26:54.752 -> [ 44853][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
17:26:54.848 -> [ 44949][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 71896
17:26:54.944 -> [ 45065][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
17:26:55.073 -> [ 45278][I][BluetoothA2DPSink.cpp:992] av_hdl_avrc_evt(): [BT_AV] remote rn_cap: count 7, bitmask 0x1f06
17:26:56.871 -> [ 47199][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
17:26:56.967 -> [ 47200][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72080
17:26:57.031 -> [ 47254][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
17:26:57.159 -> [ 47374][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 100%
17:26:57.287 -> [ 47412][I][BluetoothA2DPSinkQueued.cpp:100] write_audio(): [BT_API] ringbuffer data increased! mode changed: RINGBUFFER_MODE_PROCESSING
17:26:57.415 -> [ 47488][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 100
17:26:57.544 -> [ 47632][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
17:26:57.639 -> [ 47641][W][BluetoothA2DPSinkQueued.cpp:93] write_audio(): [BT_API] ringbuffer overflowed, ready to decrease data! mode changed: RINGBUFFER_MODE_DROPPING
17:26:57.799 -> [ 47860][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
17:26:57.927 -> [ 48024][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
17:26:58.023 -> [ 48126][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
17:26:58.119 -> [ 48239][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
17:26:58.248 -> [ 48342][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
17:26:58.344 -> [ 48455][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!

The last few lines repeat...
I am pretty concerned based on your last message that there is some kind of hardware error here. Almost like the breadboards that I'm using are flawed, or the jumper wires are too thin, or something like that. So many potential variables...

I guess the access to is_i2s_active needs to by synchronized.
Can you check if is good enought to define this variable as volatile ?

I guess the access to is_i2s_active needs to by synchronized. Can you check if is good enought to define this variable as volatile ?

I tried that here. This is the full log:
18:23:29.012 -> �(���.��L��$5�[    36][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 222316
18:23:29.511 -> [    36][I][BluetoothA2DPSink.cpp:172] start(): [BT_AV] Device name will be set to 'mrhemMusicBasic7'
18:23:29.608 -> [   103][I][BluetoothA2DPSink.cpp:238] init_i2s(): [BT_AV] init_i2s
18:23:29.705 -> [   174][I][BluetoothA2DPSink.cpp:240] init_i2s(): [BT_AV] init_i2s is_i2s_output
18:23:29.768 -> [   356][I][BluetoothA2DPSink.cpp:320] init_bluetooth(): [BT_AV] controller initialized
18:23:30.078 -> [   680][I][BluetoothA2DPSink.cpp:329] init_bluetooth(): [BT_AV] bluedroid initialized
18:23:30.143 -> [   749][I][BluetoothA2DPSink.cpp:338] init_bluetooth(): [BT_AV] bluedroid enabled
18:23:30.238 -> [   749][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:30.366 -> [   845][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable true
18:23:30.495 -> [   852][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
18:23:30.558 -> [   960][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:30.686 -> [  1041][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
18:23:30.751 -> [  1162][I][BluetoothA2DPSink.cpp:643] av_hdl_a2d_evt(): [BT_AV] A2DP PROF STATE: Init Compl
18:23:30.847 ->
18:23:30.878 -> [  1243][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
18:23:30.942 -> [   960][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 116652
18:23:31.038 -> [  1424][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
18:23:38.186 -> [  8812][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 16
18:23:38.418 -> [  9046][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:38.546 -> [  9046][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
18:23:38.673 -> [  9146][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connecting, [c0:b6:58:63:bf:5d]
18:23:38.801 -> [  9282][I][BluetoothA2DPSink.cpp:814] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTING
18:23:38.929 -> [  9397][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:39.025 -> [  9518][I][BluetoothA2DPSink.cpp:1463] av_hdl_avrc_tg_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
18:23:39.153 -> [  9644][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:39.281 -> [  9765][I][BluetoothA2DPSink.cpp:1479] av_hdl_avrc_tg_evt(): [BT_AV] AVRC register event notification: 13, param: 0x0
18:23:39.409 -> [  9890][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:39.538 -> [ 10011][I][BluetoothA2DPSink.cpp:661] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , codec type 0
18:23:39.634 -> [ 10116][I][BluetoothA2DPSink.cpp:673] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , sample_rate 44100
18:23:39.763 -> [ 10227][I][BluetoothA2DPSink.cpp:684] handle_audio_cfg(): [BT_AV] configure audio player 21-15-2-35
18:23:39.859 ->
18:23:39.859 -> [ 10335][I][BluetoothA2DPSink.cpp:692] handle_audio_cfg(): [BT_AV] audio player configured, samplerate=44100
18:23:39.956 -> [ 10449][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:40.084 -> [ 10570][I][BluetoothA2DPSink.cpp:939] av_hdl_avrc_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
18:23:40.213 -> [ 10692][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:40.341 -> [ 10813][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
18:23:40.438 -> [ 10909][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:40.567 -> [ 11030][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
18:23:40.662 -> [ 11145][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:40.791 -> [ 11266][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
18:23:40.886 -> [ 11381][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connected, [c0:b6:58:63:bf:5d]
18:23:41.046 -> [ 11515][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
18:23:41.046 -> [ 11515][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
18:23:41.142 -> [ 11630][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable false
18:23:41.271 -> [ 11745][I][BluetoothA2DPSinkQueued.cpp:6] bt_i2s_task_start_up(): [BT_API] ringbuffer data empty! mode changed: RINGBUFFER_MODE_PREFETCHING
18:23:41.398 -> [ 11893][I][BluetoothA2DPSinkQueued.cpp:21] bt_i2s_task_start_up(): [BT_AV] BtI2STask Started
18:23:41.526 -> [ 11992][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:41.622 -> [ 12066][I][BluetoothA2DPSink.cpp:550] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT stat:0
18:23:41.750 -> [ 12113][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
18:23:41.847 -> [ 12224][I][BluetoothA2DPSink.cpp:552] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT remote name:iPhone (16)
18:23:41.974 -> [ 12320][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:42.102 -> [ 12538][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
18:23:42.198 -> [ 12570][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
18:23:42.295 -> [ 12666][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 71908
18:23:42.390 -> [ 12782][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:42.519 -> [ 12995][I][BluetoothA2DPSink.cpp:992] av_hdl_avrc_evt(): [BT_AV] remote rn_cap: count 7, bitmask 0x1f06
18:23:44.096 -> [ 14693][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
18:23:44.191 -> [ 14694][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 72092
18:23:44.255 -> [ 14747][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:44.383 -> [ 14868][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x1, My Dad's Gone Crazy (feat. Hailie Jade)
18:23:44.543 -> [ 15018][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:44.671 -> [ 15139][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x2, Eminem
18:23:44.767 -> [ 15255][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:44.895 -> [ 15375][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x4,
18:23:44.992 -> [ 15485][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:45.120 -> [ 15606][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x8, 1
18:23:45.249 -> [ 15716][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
18:23:45.345 -> [ 15830][I][BluetoothA2DPSinkQueued.cpp:100] write_audio(): [BT_API] ringbuffer data increased! mode changed: RINGBUFFER_MODE_PROCESSING
18:23:45.504 -> [ 15837][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x10, 126
18:23:45.601 -> [ 15981][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
18:23:45.728 -> [ 15990][W][BluetoothA2DPSinkQueued.cpp:93] write_audio(): [BT_API] ringbuffer overflowed, ready to decrease data! mode changed: RINGBUFFER_MODE_DROPPING
18:23:45.888 -> [ 16199][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
18:23:45.985 -> [ 16363][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
18:23:46.081 -> [ 16465][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
18:23:46.209 -> [ 16578][W][BluetoothA2DPSinkQueued.cpp:81] write_audio(): [BT_API] ringbuffer is full, drop this packet!
18:23:46.305 -> [ 16680][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive

--- repeats endlessly

But it is strange: the in the above log the entry for i2s_start is missing...
If a line is missing from the log, may be due to arduino ide maintainers closing discussion for a huge issue, then having its assignee leave, without being replaced https://github.com/arduino/arduino-ide/issues/2262 https://github.com/arduino/arduino-ide/issues/812

That should be good enough. I am still confused from what I see: some critical events seem to be missing.
Can you try to add the logic from https://github.com/pschatzmann/ESP32-A2DP/blob/main/examples/bt_music_receiver_status_callback/bt_music_receiver_status_callback.ino
I really need the confirmation that the playback status is working....

Here is the log from the code you requested. Hopefully the issue was not that I had "Info" logging instead of "Debug". Debug is active now..:
19:18:18.063 -> ets Jul 29 2019 12:21:46
19:18:18.108 ->
19:18:18.108 -> rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
19:18:18.108 -> configsip: 0, SPIWP:0xee
19:18:18.108 -> clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
19:18:18.108 -> mode:DIO, clock div:1
19:18:18.108 -> load:0x3fff0030,len:1344
19:18:18.108 -> load:0x40078000,len:13964
19:18:18.108 -> load:0x40080400,len:3600
19:18:18.108 -> entry 0x400805f0
19:18:18.471 -> [    25][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
19:18:18.503 -> [    36][D][BluetoothA2DPSink.cpp:160] start(): [BT_AV] start
19:18:18.503 -> [    36][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 222372
19:18:18.503 -> [    45][I][BluetoothA2DPSink.cpp:172] start(): [BT_AV] Device name will be set to 'MyMusic'
19:18:18.503 -> [    53][D][BluetoothA2DPSink.cpp:1163] init_nvs(): [BT_AV] init_nvs
19:18:18.503 -> [    59][D][BluetoothA2DPCommon.cpp:152] get_last_connection(): [BT_AV] get_last_connection
19:18:18.535 -> [    68][D][BluetoothA2DPCommon.cpp:175] get_last_connection(): [BT_AV] => c0:b6:58:63:bf:5d
19:18:18.535 -> [    75][I][BluetoothA2DPSink.cpp:238] init_i2s(): [BT_AV] init_i2s
19:18:18.535 -> [    81][I][BluetoothA2DPSink.cpp:240] init_i2s(): [BT_AV] init_i2s is_i2s_output
19:18:18.535 -> [    89][D][BluetoothA2DPSink.cpp:315] init_bluetooth(): [BT_AV] init_bluetooth
19:18:18.658 -> [   190][I][BluetoothA2DPSink.cpp:320] init_bluetooth(): [BT_AV] controller initialized
19:18:18.964 -> [   502][I][BluetoothA2DPSink.cpp:329] init_bluetooth(): [BT_AV] bluedroid initialized
19:18:19.028 -> [   572][I][BluetoothA2DPSink.cpp:338] init_bluetooth(): [BT_AV] bluedroid enabled
19:18:19.028 -> [   572][D][BluetoothA2DPSink.cpp:455] app_task_start_up(): [BT_AV] app_task_start_up
19:18:19.028 -> [   575][D][BluetoothA2DPSink.cpp:1289] ccall_app_task_handler(): [BT_AV] ccall_app_task_handler
19:18:19.028 -> [   584][D][BluetoothA2DPSink.cpp:411] app_task_handler(): [BT_AV] app_task_handler
19:18:19.060 -> [   591][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 0
19:18:19.060 -> [   601][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:19.060 -> [   608][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:18:19.060 -> [   616][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:19.092 -> [   626][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:19.092 -> [   634][D][BluetoothA2DPSink.cpp:1319] ccall_av_hdl_stack_evt(): [BT_AV] ccall_av_hdl_stack_evt
19:18:19.092 -> [   643][D][BluetoothA2DPSink.cpp:1015] av_hdl_stack_evt(): [BT_AV] av_hdl_stack_evt evt 0
19:18:19.092 -> [   651][D][BluetoothA2DPSink.cpp:1020] av_hdl_stack_evt(): [BT_AV] av_hdl_stack_evt av_hdl_stack_evt BT_APP_EVT_STACK_UP
19:18:19.124 -> [   661][D][BluetoothA2DPSink.cpp:1029] av_hdl_stack_evt(): [BT_AV] AVRCP controller initialized!
19:18:19.124 -> [   662][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:19.124 -> [   670][D][BluetoothA2DPSink.cpp:1072] av_hdl_stack_evt(): [BT_AV] set_scan_mode_connectable(true)
19:18:19.156 -> [   678][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:18:19.156 -> [   687][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable true
19:18:19.156 -> [   696][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:18:19.156 -> [   670][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 130044
19:18:19.188 -> [   712][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:18:19.188 -> [   727][D][BluetoothA2DPSink.cpp:1108] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_CFG_EVT
19:18:19.188 -> [   737][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 16
19:18:19.188 -> [   746][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:19.220 -> [   753][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:18:19.220 -> [   754][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:19.220 -> [   762][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:19.220 -> [   770][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:18:19.252 -> [   780][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:19.252 -> [   787][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:19.252 -> [   795][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:18:19.252 -> [   804][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:18:19.284 -> [   812][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 4
19:18:19.284 -> [   818][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:19.284 -> [   826][I][BluetoothA2DPSink.cpp:643] av_hdl_a2d_evt(): [BT_AV] A2DP PROF STATE: Init Compl
19:18:19.284 ->
19:18:19.284 -> [   835][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:18:34.276 -> [ 15817][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:34.277 -> [ 15817][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 16
19:18:34.449 -> [ 15989][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:18:34.449 -> [ 15990][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:18:34.449 -> [ 15994][D][BluetoothA2DPSink.cpp:1092] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_CONNECTION_STATE_EVT
19:18:34.449 -> [ 16004][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 16
19:18:34.482 -> [ 16020][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.482 -> [ 16021][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:18:34.482 -> [ 16029][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:34.482 -> [ 16039][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:34.513 -> [ 16047][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:18:34.513 -> [ 16021][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:18:34.513 -> [ 16056][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 0
19:18:34.513 -> [ 16064][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:18:34.545 -> [ 16072][D][BluetoothA2DPSink.cpp:623] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_CONNECTION_STATE_EVT
19:18:34.545 -> [ 16080][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 12
19:18:34.545 -> [ 16089][D][BluetoothA2DPSink.cpp:748] handle_connection_state(): [BT_AV] handle_connection_state evt 0
19:18:34.577 -> [ 16099][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.577 -> [ 16108][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
19:18:34.577 -> [ 16115][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:18:34.578 -> [ 16124][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connecting, [c0:b6:58:63:bf:5d]
19:18:34.609 -> [ 16133][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:18:34.609 -> [ 16144][I][BluetoothA2DPSink.cpp:814] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTING
19:18:34.609 -> [ 16152][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 12
19:18:34.641 -> Connecting
19:18:34.641 -> [ 16172][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.641 -> [ 16182][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:18:34.641 -> [ 16182][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:18:34.641 -> [ 16188][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:34.673 -> [ 16197][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:18:34.673 -> [ 16207][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:34.673 -> [ 16214][D][BluetoothA2DPSink.cpp:1101] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_CFG_EVT
19:18:34.673 -> [ 16222][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:18:34.705 -> [ 16231][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 16
19:18:34.705 -> [ 16240][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 0
19:18:34.705 -> [ 16250][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.705 -> [ 16258][I][BluetoothA2DPSink.cpp:1463] av_hdl_avrc_tg_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
19:18:34.737 -> [ 16265][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:34.737 -> [ 16275][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:18:34.737 -> [ 16284][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:34.769 -> [ 16293][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:34.769 -> [ 16301][D][BluetoothA2DPSink.cpp:584] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_CONNECTION_STATE_EVT
19:18:34.769 -> [ 16311][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:34.769 -> [ 16321][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 12
19:18:34.801 -> [ 16329][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:18:34.801 -> [ 16339][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.801 -> [ 16348][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 4
19:18:34.833 -> [ 16355][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:34.833 -> [ 16363][I][BluetoothA2DPSink.cpp:1479] av_hdl_avrc_tg_evt(): [BT_AV] AVRC register event notification: 13, param: 0x0
19:18:34.833 -> [ 16372][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:34.833 -> [ 16382][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:34.866 -> [ 16390][D][BluetoothA2DPSink.cpp:596] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_REMOTE_FEATURES_EVT
19:18:34.866 -> [ 16399][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:34.866 -> [ 16409][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x5, param len 12
19:18:34.897 -> [ 16419][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:34.897 -> [ 16429][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.897 -> [ 16437][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:18:34.897 -> [ 16443][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:18:34.929 -> [ 16452][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 2
19:18:34.929 -> [ 16460][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:18:34.929 -> [ 16468][D][BluetoothA2DPSink.cpp:633] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_AUDIO_CFG_EVT
19:18:34.929 -> [ 16476][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 12
19:18:34.961 -> [ 16485][D][BluetoothA2DPSink.cpp:658] handle_audio_cfg(): [BT_AV] handle_audio_cfg evt 2
19:18:34.961 -> [ 16495][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:34.961 -> [ 16502][I][BluetoothA2DPSink.cpp:661] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , codec type 0
19:18:34.961 -> [ 16509][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:18:34.993 -> [ 16518][I][BluetoothA2DPSink.cpp:673] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , sample_rate 44100
19:18:34.993 -> [ 16526][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:18:34.993 -> [ 16536][I][BluetoothA2DPSink.cpp:684] handle_audio_cfg(): [BT_AV] configure audio player 21-15-2-35
19:18:34.993 ->
19:18:34.993 -> [ 16543][D][BluetoothA2DPSink.cpp:1092] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_CONNECTION_STATE_EVT
19:18:35.025 -> [ 16552][I][BluetoothA2DPSink.cpp:692] handle_audio_cfg(): [BT_AV] audio player configured, samplerate=44100
19:18:35.025 -> [ 16562][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 16
19:18:35.025 -> [ 16572][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:18:35.057 -> [ 16581][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.057 -> [ 16590][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.057 -> [ 16607][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.057 -> [ 16615][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.089 -> [ 16623][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 0
19:18:35.089 -> [ 16631][I][BluetoothA2DPSink.cpp:939] av_hdl_avrc_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
19:18:35.089 -> [ 16641][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x5
19:18:35.089 -> [ 16650][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.121 -> [ 16660][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.121 -> [ 16667][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.121 -> [ 16676][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 5
19:18:35.153 -> [ 16684][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
19:18:35.153 -> [ 16692][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:18:35.153 -> [ 16700][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.153 -> [ 16708][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.185 -> [ 16710][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.185 -> [ 16719][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.185 -> [ 16727][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:18:35.185 -> [ 16735][D][BluetoothA2DPSink.cpp:604] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_GET_RN_CAPABILITIES_RSP_EVT
19:18:35.217 -> [ 16744][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 1
19:18:35.217 -> [ 16755][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x6, param len 12
19:18:35.217 -> [ 16763][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
19:18:35.249 -> [ 16773][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.249 -> [ 16783][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:18:35.249 -> [ 16789][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.249 -> [ 16798][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.282 -> [ 16807][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.282 -> [ 16817][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.282 -> [ 16825][D][BluetoothA2DPSink.cpp:596] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_REMOTE_FEATURES_EVT
19:18:35.314 -> [ 16832][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:18:35.314 -> [ 16843][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x5, param len 12
19:18:35.314 -> [ 16851][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 0
19:18:35.314 -> [ 16861][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.345 -> [ 16868][D][BluetoothA2DPSink.cpp:623] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_CONNECTION_STATE_EVT
19:18:35.345 -> [ 16875][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:18:35.345 -> [ 16885][D][BluetoothA2DPSink.cpp:748] handle_connection_state(): [BT_AV] handle_connection_state evt 0
19:18:35.345 -> [ 16893][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:18:35.377 -> [ 16903][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
19:18:35.377 -> [ 16910][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 12
19:18:35.377 -> [ 16920][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connected, [c0:b6:58:63:bf:5d]
19:18:35.409 -> [ 16930][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.409 -> [ 16941][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
19:18:35.409 -> [ 16957][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable false
19:18:35.409 -> Connected
19:18:35.409 -> [ 16977][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x6
19:18:35.442 -> [ 16977][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.442 -> [ 16986][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.442 -> [ 16990][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:18:35.473 -> [ 16994][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.473 -> [ 17003][I][BluetoothA2DPSink.cpp:550] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT stat:0
19:18:35.473 -> [ 17011][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 6
19:18:35.473 -> [ 17020][I][BluetoothA2DPSink.cpp:552] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT remote name:iPhone (16)
19:18:35.505 -> [ 17028][I][BluetoothA2DPSink.cpp:992] av_hdl_avrc_evt(): [BT_AV] remote rn_cap: count 7, bitmask 0x1f06
19:18:35.505 -> [ 17048][D][BluetoothA2DPSink.cpp:883] av_new_track(): [BT_AV] av_new_track
19:18:35.505 -> [ 17055][D][BluetoothA2DPSink.cpp:896] av_playback_changed(): [BT_AV] av_playback_changed
19:18:35.505 -> [ 17063][D][BluetoothA2DPSink.cpp:702] handle_avrc_connection_state(): [BT_AV] handle_avrc_connection_state state 1
19:18:35.537 -> [ 17073][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x5
19:18:35.537 -> [ 17082][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.537 -> [ 17083][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.505 -> [ 17055][D][BluetoothA2DPSink.cpp:896] av_playback_changed(): [BT_AV] av_playback_changed
19:18:35.505 -> [ 17063][D][BluetoothA2DPSink.cpp:702] handle_avrc_connection_state(): [BT_AV] handle_avrc_connection_state state 1
19:18:35.537 -> [ 17073][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x5
19:18:35.537 -> [ 17082][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.537 -> [ 17083][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.569 -> [ 17092][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.569 -> [ 17100][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.569 -> [ 17108][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.569 -> [ 17116][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:35.601 -> [ 17124][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 5
19:18:35.601 -> [ 17135][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:35.601 -> [ 17142][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
19:18:35.601 -> [ 17151][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:35.633 -> [ 17159][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:18:35.633 -> [ 17168][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.633 -> [ 17177][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.665 -> [ 17184][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.665 -> [ 17194][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.665 -> [ 17203][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.665 -> [ 17211][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:18:35.697 -> [ 17218][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:35.697 -> [ 17227][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 1
19:18:35.697 -> [ 17237][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:35.697 -> [ 17246][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
19:18:35.729 -> [ 17254][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:35.729 -> [ 17264][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:35.729 -> [ 17273][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.729 -> [ 17282][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.761 -> [ 17289][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.761 -> [ 17299][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.761 -> [ 17308][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.793 -> [ 17315][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.793 -> [ 17323][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:35.793 -> [ 17332][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:35.793 -> [ 17342][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:35.825 -> [ 17350][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x1, My Dad's Gone Crazy (feat. Hailie Jade)
19:18:35.825 -> [ 17358][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:35.825 -> [ 17370][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:35.857 -> [ 17380][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.857 -> [ 17389][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.857 -> [ 17395][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.857 -> [ 17405][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.889 -> [ 17414][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.889 -> [ 17422][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.889 -> [ 17430][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:35.889 -> [ 17438][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:35.921 -> [ 17448][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:35.921 -> [ 17456][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x2, Eminem
19:18:35.921 -> [ 17464][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:35.953 -> [ 17474][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:35.953 -> [ 17484][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:35.953 -> [ 17492][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:35.953 -> [ 17499][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:35.985 -> [ 17509][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:35.985 -> [ 17518][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:35.985 -> [ 17526][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:35.985 -> [ 17534][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:36.017 -> [ 17542][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:36.017 -> [ 17552][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:36.017 -> [ 17560][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x4,
19:18:36.017 -> [ 17568][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:36.049 -> [ 17577][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:36.049 -> [ 17587][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:36.049 -> [ 17596][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:36.081 -> [ 17602][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:36.081 -> [ 17613][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:36.081 -> [ 17621][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:36.081 -> [ 17629][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:36.114 -> [ 17637][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:18:36.114 -> [ 17645][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:36.114 -> [ 17656][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:18:36.114 -> [ 17663][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x8, 1
19:18:36.146 -> [ 17672][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:18:36.146 -> [ 17681][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:36.146 -> [ 17691][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:36.177 -> [ 17699][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:36.177 -> [ 17716][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:36.177 -> [ 17724][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:36.177 -> [ 17732][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:36.210 -> [ 17740][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x10, 126
19:18:36.210 -> [ 17749][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:18:36.210 -> [ 17758][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:36.210 -> [ 17768][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:36.245 -> [ 17776][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:36.245 -> [ 17784][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:18:36.245 -> [ 17792][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x20,
19:18:37.296 -> [ 18859][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:18:37.328 -> [ 18859][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:18:37.328 -> [ 18865][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:18:37.328 -> [ 18874][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:37.328 -> [ 18881][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:18:37.360 -> [ 18890][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:37.360 -> [ 18900][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:37.360 -> [ 18908][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:18:37.360 -> [ 18917][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
19:18:37.392 -> [ 18923][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:18:37.392 -> [ 18925][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 100%
19:18:37.392 -> [ 18933][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:18:37.392 -> [ 18943][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 100
19:18:37.424 -> [ 18950][D][BluetoothA2DPSink.cpp:1096] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_STATE_EVT
19:18:37.424 -> [ 18970][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 16
19:18:37.424 -> [ 18980][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:37.456 -> [ 18987][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:18:37.456 -> [ 18995][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:37.456 -> [ 19003][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.456 -> [ 19005][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:37.488 -> [ 19014][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:18:37.488 -> [ 19021][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:18:37.488 -> [ 19032][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.488 -> [ 19038][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 1
19:18:37.521 -> [ 19046][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:18:37.521 -> [ 19054][D][BluetoothA2DPSink.cpp:628] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_AUDIO_STATE_EVT
19:18:37.521 -> [ 19062][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:18:37.521 -> [ 19071][D][BluetoothA2DPSink.cpp:710] handle_audio_state(): [BT_AV] handle_audio_state evt 1
19:18:37.552 -> [ 19080][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:18:37.552 -> [ 19089][I][BluetoothA2DPSink.cpp:712] handle_audio_state(): [BT_AV] A2DP audio state: Started
19:18:37.552 -> [ 19096][D][BluetoothA2DPSink.cpp:592] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_CHANGE_NOTIFY_EVT
19:18:37.584 -> Started
19:18:37.584 -> [ 19115][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 12
19:18:37.584 -> [ 19125][I][BluetoothA2DPSink.cpp:724] handle_audio_state(): [BT_AV] i2s_start
19:18:37.584 -> [ 19125][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:18:37.584 -> [ 19139][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:18:37.616 -> [ 19142][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.616 -> [ 19148][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:18:37.616 -> [ 19166][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:18:37.616 -> [ 19171][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.648 -> [ 19174][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:18:37.648 -> [ 19190][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 4
19:18:37.648 -> [ 19197][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.648 -> [ 19198][D][BluetoothA2DPSink.cpp:911] av_notify_evt_handler(): [BT_AV] av_notify_evt_handler
19:18:37.680 -> [ 19214][D][BluetoothA2DPSink.cpp:918] av_notify_evt_handler(): [BT_AV] av_notify_evt_handler ESP_AVRC_RN_PLAY_STATUS_CHANGE 1, to 1
19:18:37.680 -> [ 19222][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.680 -> [ 19226][D][BluetoothA2DPSink.cpp:896] av_playback_changed(): [BT_AV] av_playback_changed
19:18:37.727 -> [ 19249][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.727 -> [ 19273][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.758 -> [ 19312][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.758 -> [ 19328][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.837 -> [ 19368][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.837 -> [ 19384][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.869 -> [ 19424][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.869 -> [ 19439][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.949 -> [ 19480][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.949 -> [ 19496][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.981 -> [ 19537][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:37.981 -> [ 19552][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:38.045 -> [ 19593][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:38.045 -> [ 19609][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:38.117 -> [ 19650][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:18:38.117 -> [ 19666][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback

The above logs are from just the page you linked to.  In the next Github comment, I will provide logs for some code that has some more of my code integrated (will be show the code there)

So in the last comment, I just used the code that you linked to. But this is with some more of my code integrated.
The code:
#include "BluetoothA2DPSink.h"

BluetoothA2DPSink a2dp_sink;

// for esp_a2d_connection_state_t see https://docs.espressif.com/projects/esp-idf/en/latest/esp32/api-reference/bluetooth/esp_a2dp.html#_CPPv426esp_a2d_connection_state_t
void connection_state_changed(esp_a2d_connection_state_t state, void *ptr){
  Serial.println(a2dp_sink.to_str(state));
}

// for esp_a2d_audio_state_t see https://docs.espressif.com/projects/esp-idf/en/latest/esp32/api-reference/bluetooth/esp_a2dp.html#_CPPv421esp_a2d_audio_state_t
void audio_state_changed(esp_a2d_audio_state_t state, void *ptr){
  Serial.println(a2dp_sink.to_str(state));
}

void setup() {
  Serial.begin(115200);
  a2dp_sink.set_on_connection_state_changed(connection_state_changed);
  a2dp_sink.set_on_audio_state_changed(audio_state_changed);

  int mclkPin=3;
  i2s_pin_config_t external_pin_config = {
        .mck_io_num = mclkPin,
        .bck_io_num =33,
        .ws_io_num = 32,
        .data_out_num = 22,
        .data_in_num = I2S_PIN_NO_CHANGE,
    };
  static const i2s_config_t externalDAC_config = {
        .mode = (i2s_mode_t) (I2S_MODE_MASTER | I2S_MODE_TX),
        .sample_rate =  44100, // corrected by info from bluetooth
        .bits_per_sample = (i2s_bits_per_sample_t) 16,
        .channel_format = I2S_CHANNEL_FMT_RIGHT_LEFT,
        .communication_format = (i2s_comm_format_t)I2S_COMM_FORMAT_STAND_MSB,
        .intr_alloc_flags = 0, // default interrupt priority
        .dma_buf_count = 8,
        .dma_buf_len = 64,
        .use_apll = true,
        .tx_desc_auto_clear = true, // avoiding noise in case of data unavailability
        .mclk_multiple=(i2s_mclk_multiple_t) 256,

  };

  bool externalDAC=true;

    a2dp_sink.set_pin_config(external_pin_config);
    a2dp_sink.set_i2s_config(externalDAC_config);
    a2dp_sink.i2s_mclk_pin_select(mclkPin);

  a2dp_sink.start("MyMusic");
}

void loop() {
  delay(1000); // do nothing
}

The log:
19:24:16.755 -> ets Jul 29 2019 12:21:46
19:24:16.755 ->
19:24:16.755 -> rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
19:24:16.755 -> configsip: 0, SPIWP:0xee
19:24:16.755 -> clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
19:24:16.755 -> mode:DIO, clock div:1
19:24:16.755 -> load:0x3fff0030,len:1344
19:24:16.755 -> load:0x40078000,len:13964
19:24:16.755 -> load:0x40080400,len:3600
19:24:16.755 -> entry 0x400805f0
19:24:17.131 -> [    25][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
19:24:17.131 -> [    36][D][BluetoothA2DPSink.cpp:160] start(): [BT_AV] start
19:24:17.131 -> [    36][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 222372
19:24:17.131 -> [    45][I][BluetoothA2DPSink.cpp:172] start(): [BT_AV] Device name will be set to 'MyMusic'
19:24:17.163 -> [    53][D][BluetoothA2DPSink.cpp:1163] init_nvs(): [BT_AV] init_nvs
19:24:17.163 -> [    59][D][BluetoothA2DPCommon.cpp:152] get_last_connection(): [BT_AV] get_last_connection
19:24:17.163 -> [    68][D][BluetoothA2DPCommon.cpp:175] get_last_connection(): [BT_AV] => c0:b6:58:63:bf:5d
19:24:17.163 -> [    75][I][BluetoothA2DPSink.cpp:238] init_i2s(): [BT_AV] init_i2s
19:24:17.209 -> [    81][I][BluetoothA2DPSink.cpp:240] init_i2s(): [BT_AV] init_i2s is_i2s_output
19:24:17.209 -> [    89][D][BluetoothA2DPSink.cpp:315] init_bluetooth(): [BT_AV] init_bluetooth
19:24:17.290 -> [   191][I][BluetoothA2DPSink.cpp:320] init_bluetooth(): [BT_AV] controller initialized
19:24:17.626 -> [   518][I][BluetoothA2DPSink.cpp:329] init_bluetooth(): [BT_AV] bluedroid initialized
19:24:17.690 -> [   589][I][BluetoothA2DPSink.cpp:338] init_bluetooth(): [BT_AV] bluedroid enabled
19:24:17.690 -> [   589][D][BluetoothA2DPSink.cpp:455] app_task_start_up(): [BT_AV] app_task_start_up
19:24:17.690 -> [   593][D][BluetoothA2DPSink.cpp:1289] ccall_app_task_handler(): [BT_AV] ccall_app_task_handler
19:24:17.690 -> [   602][D][BluetoothA2DPSink.cpp:411] app_task_handler(): [BT_AV] app_task_handler
19:24:17.722 -> [   609][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 0
19:24:17.722 -> [   619][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:17.722 -> [   625][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:24:17.722 -> [   634][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:17.754 -> [   644][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:17.754 -> [   652][D][BluetoothA2DPSink.cpp:1319] ccall_av_hdl_stack_evt(): [BT_AV] ccall_av_hdl_stack_evt
19:24:17.754 -> [   660][D][BluetoothA2DPSink.cpp:1015] av_hdl_stack_evt(): [BT_AV] av_hdl_stack_evt evt 0
19:24:17.754 -> [   668][D][BluetoothA2DPSink.cpp:1020] av_hdl_stack_evt(): [BT_AV] av_hdl_stack_evt av_hdl_stack_evt BT_APP_EVT_STACK_UP
19:24:17.787 -> [   679][D][BluetoothA2DPSink.cpp:1029] av_hdl_stack_evt(): [BT_AV] AVRCP controller initialized!
19:24:17.787 -> [   680][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:17.787 -> [   688][D][BluetoothA2DPSink.cpp:1072] av_hdl_stack_evt(): [BT_AV] set_scan_mode_connectable(true)
19:24:17.818 -> [   696][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:24:17.818 -> [   705][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable true
19:24:17.818 -> [   714][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:24:17.818 -> [   688][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 130044
19:24:17.850 -> [   730][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:24:17.850 -> [   745][D][BluetoothA2DPSink.cpp:1108] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_CFG_EVT
19:24:17.850 -> [   754][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 16
19:24:17.850 -> [   764][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:17.882 -> [   771][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:24:17.882 -> [   771][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:17.882 -> [   779][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:17.914 -> [   788][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:24:17.914 -> [   798][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:17.914 -> [   805][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:17.914 -> [   813][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:24:17.947 -> [   821][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:24:17.947 -> [   829][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 4
19:24:17.947 -> [   836][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:17.947 -> [   844][I][BluetoothA2DPSink.cpp:643] av_hdl_a2d_evt(): [BT_AV] A2DP PROF STATE: Init Compl
19:24:17.947 ->
19:24:17.947 -> [   852][I][BluetoothA2DPSink.cpp:566] app_gap_callback(): [BT_AV] event: 10
19:24:41.948 -> [ 24867][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callb
19:24:42.316 -> [ 25211][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:24:42.316 -> [ 25211][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:24:42.316 -> [ 25216][D][BluetoothA2DPSink.cpp:1092] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_CONNECTION_STATE_EVT
19:24:42.316 -> [ 25226][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 16
19:24:42.348 -> [ 25235][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.348 -> [ 25242][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:24:42.348 -> [ 25251][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.348 -> [ 25261][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.380 -> [ 25269][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:24:42.380 -> [ 25277][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 0
19:24:42.380 -> [ 25284][D][BluetoothA2DPSink.cpp:623] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_CONNECTION_STATE_EVT
19:24:42.380 -> [ 25294][D][BluetoothA2DPSink.cpp:748] handle_connection_state(): [BT_AV] handle_connection_state evt 0
19:24:42.412 -> [ 25303][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
19:24:42.412 -> [ 25313][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connecting, [c0:b6:58:63:bf:5d]
19:24:42.412 -> [ 25273][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:42.444 -> [ 25324][I][BluetoothA2DPSink.cpp:814] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTING
19:24:42.444 -> Connecting
19:24:42.444 -> [ 25333][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:42.444 -> [ 25353][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 12
19:24:42.476 -> [ 25361][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.476 -> [ 25368][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:24:42.476 -> [ 25368][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:42.476 -> [ 25376][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.508 -> [ 25385][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:42.508 -> [ 25395][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.508 -> [ 25403][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 12
19:24:42.508 -> [ 25411][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:42.540 -> [ 25421][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.540 -> [ 25429][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 0
19:24:42.540 -> [ 25436][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:24:42.540 -> [ 25445][I][BluetoothA2DPSink.cpp:1463] av_hdl_avrc_tg_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
19:24:42.572 -> [ 25453][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:24:42.572 -> [ 25464][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:24:42.572 -> [ 25471][D][BluetoothA2DPSink.cpp:1101] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_CFG_EVT
19:24:42.604 -> [ 25480][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.604 -> [ 25489][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 16
19:24:42.604 -> [ 25499][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.604 -> [ 25509][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.636 -> [ 25517][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:42.636 -> [ 25523][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:42.636 -> [ 25532][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 4
19:24:42.636 -> [ 25541][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:42.668 -> [ 25549][I][BluetoothA2DPSink.cpp:1479] av_hdl_avrc_tg_evt(): [BT_AV] AVRC register event notification: 13, param: 0x0
19:24:42.668 -> [ 25557][D][BluetoothA2DPSink.cpp:584] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_CONNECTION_STATE_EVT
19:24:42.668 -> [ 25568][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:42.700 -> [ 25578][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 12
19:24:42.700 -> [ 25587][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.700 -> [ 25597][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.700 -> [ 25607][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.732 -> [ 25613][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:42.732 -> [ 25621][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:24:42.732 -> [ 25630][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:42.732 -> [ 25638][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 2
19:24:42.764 -> [ 25646][D][BluetoothA2DPSink.cpp:596] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_REMOTE_FEATURES_EVT
19:24:42.764 -> [ 25654][D][BluetoothA2DPSink.cpp:633] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_AUDIO_CFG_EVT
19:24:42.764 -> [ 25664][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x5, param len 12
19:24:42.796 -> [ 25673][D][BluetoothA2DPSink.cpp:658] handle_audio_cfg(): [BT_AV] handle_audio_cfg evt 2
19:24:42.796 -> [ 25683][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.796 -> [ 25691][I][BluetoothA2DPSink.cpp:661] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , codec type 0
19:24:42.796 -> [ 25697][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:42.828 -> [ 25706][I][BluetoothA2DPSink.cpp:673] handle_audio_cfg(): [BT_AV] a2dp audio_cfg_cb , sample_rate 44100
19:24:42.828 -> [ 25715][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:42.828 -> [ 25724][I][BluetoothA2DPSink.cpp:684] handle_audio_cfg(): [BT_AV] configure audio player 21-15-2-35
19:24:42.828 ->
19:24:42.828 -> [ 25732][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 12
19:24:42.860 -> [ 25741][I][BluetoothA2DPSink.cpp:692] handle_audio_cfg(): [BT_AV] audio player configured, samplerate=44100
19:24:42.860 -> [ 25751][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.860 -> [ 25760][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:24:42.892 -> [ 25767][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:24:42.892 -> [ 25776][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.892 -> [ 25784][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:24:42.892 -> [ 25794][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.925 -> [ 25802][D][BluetoothA2DPSink.cpp:1092] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_CONNECTION_STATE_EVT
19:24:42.925 -> [ 25810][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:42.925 -> [ 25820][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x0, param len 16
19:24:42.925 -> [ 25828][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 0
19:24:42.956 -> [ 25838][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:42.956 -> [ 25845][I][BluetoothA2DPSink.cpp:939] av_hdl_avrc_evt(): [BT_AV] AVRC conn_state evt: state 1, [c0:b6:58:63:bf:5d]
19:24:42.956 -> [ 25862][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x5
19:24:42.956 -> [ 25871][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:42.988 -> [ 25881][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:42.988 -> [ 25889][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:42.988 -> [ 25897][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 5
19:24:43.020 -> [ 25905][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
19:24:43.020 -> [ 25913][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:24:43.020 -> [ 25921][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.020 -> [ 25932][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.052 -> [ 25939][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:43.052 -> [ 25948][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 1
19:24:43.052 -> [ 25957][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
19:24:43.052 -> [ 25966][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x0
19:24:43.085 -> [ 25975][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.085 -> [ 25985][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.085 -> [ 25993][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:24:43.116 -> [ 26001][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 0
19:24:43.116 -> [ 26009][D][BluetoothA2DPSink.cpp:623] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_CONNECTION_STATE_EVT
19:24:43.116 -> [ 26014][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.116 -> [ 26018][D][BluetoothA2DPSink.cpp:748] handle_connection_state(): [BT_AV] handle_connection_state evt 0
19:24:43.148 -> [ 26027][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.148 -> [ 26036][I][BluetoothA2DPSink.cpp:753] handle_connection_state(): [BT_AV] partner address: c0:b6:58:63:bf:5d
19:24:43.148 -> [ 26044][D][BluetoothA2DPSink.cpp:604] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_GET_RN_CAPABILITIES_RSP_EVT
19:24:43.180 -> [ 26053][I][BluetoothA2DPSink.cpp:755] handle_connection_state(): [BT_AV] A2DP connection state: Connected, [c0:b6:58:63:bf:5d]
19:24:43.180 -> [ 26065][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x6, param len 12
19:24:43.180 -> [ 26076][I][BluetoothA2DPSink.cpp:819] handle_connection_state(): [BT_AV] ESP_A2D_CONNECTION_STATE_CONNECTED
19:24:43.180 -> [ 26086][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.212 -> [ 26095][I][BluetoothA2DPCommon.cpp:289] set_scan_mode_connectable(): [BT_AV] set_scan_mode_connectable false
19:24:43.212 -> Connected
19:24:43.212 -> [ 26122][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x6
19:24:43.212 -> [ 26122][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.244 -> [ 26131][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.244 -> [ 26139][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:43.244 -> [ 26147][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 6
19:24:43.244 -> [ 26155][I][BluetoothA2DPSink.cpp:992] av_hdl_avrc_evt(): [BT_AV] remote rn_cap: count 7, bitmask 0x1f06
19:24:43.276 -> [ 26155][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:43.276 -> [ 26164][D][BluetoothA2DPSink.cpp:883] av_new_track(): [BT_AV] av_new_track
19:24:43.276 -> [ 26173][I][BluetoothA2DPSink.cpp:550] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT stat:0
19:24:43.276 -> [ 26180][D][BluetoothA2DPSink.cpp:896] av_playback_changed(): [BT_AV] av_playback_changed
19:24:43.308 -> [ 26189][I][BluetoothA2DPSink.cpp:552] app_gap_callback(): [BT_AV] ESP_BT_GAP_READ_REMOTE_NAME_EVT remote name:iPhone (16)
19:24:43.308 -> [ 26197][D][BluetoothA2DPSink.cpp:702] handle_avrc_connection_state(): [BT_AV] handle_avrc_connection_state state 1
19:24:43.308 -> [ 26224][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.340 -> [ 26226][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.340 -> [ 26234][D][BluetoothA2DPSink.cpp:596] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_REMOTE_FEATURES_EVT
19:24:43.340 -> [ 26245][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x5, param len 12
19:24:43.340 -> [ 26254][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.372 -> [ 26261][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:43.372 -> [ 26261][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x5
19:24:43.372 -> [ 26270][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:43.372 -> [ 26279][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.404 -> [ 26286][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 12
19:24:43.404 -> [ 26296][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.404 -> [ 26306][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.436 -> [ 26314][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:43.436 -> [ 26321][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.436 -> [ 26329][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 5
19:24:43.436 -> [ 26338][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.468 -> [ 26346][I][BluetoothA2DPSink.cpp:984] av_hdl_avrc_evt(): [BT_AV] AVRC remote features 25b
19:24:43.468 -> [ 26353][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.468 -> [ 26361][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:24:43.468 -> [ 26372][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:43.500 -> [ 26380][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.500 -> [ 26389][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:43.500 -> [ 26399][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.532 -> [ 26408][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.532 -> [ 26416][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:43.532 -> [ 26423][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.532 -> [ 26432][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 1
19:24:43.563 -> [ 26441][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.563 -> [ 26449][I][BluetoothA2DPSink.cpp:1494] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote features 25b, CT features 2
19:24:43.563 -> [ 26457][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.563 -> [ 26466][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:43.596 -> [ 26477][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:43.596 -> [ 26485][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.596 -> [ 26494][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:43.628 -> [ 26504][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.628 -> [ 26513][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.628 -> [ 26521][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:43.628 -> [ 26528][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.660 -> [ 26536][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:24:43.660 -> [ 26545][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.660 -> [ 26553][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x1, My Dad's Gone Crazy (feat. Hailie Jade)
19:24:43.660 -> [ 26561][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.692 -> [ 26573][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:43.692 -> [ 26583][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:43.692 -> [ 26592][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.724 -> [ 26600][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:43.724 -> [ 26610][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.724 -> [ 26620][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.724 -> [ 26628][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:43.756 -> [ 26635][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.756 -> [ 26643][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:24:43.756 -> [ 26652][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.756 -> [ 26660][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x2, Eminem
19:24:43.788 -> [ 26667][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.788 -> [ 26677][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:43.788 -> [ 26687][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:43.820 -> [ 26696][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.820 -> [ 26704][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:43.820 -> [ 26714][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.820 -> [ 26724][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.852 -> [ 26756][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.884 -> [ 26763][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x4,
19:24:43.884 -> [ 26771][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.884 -> [ 26780][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:43.884 -> [ 26790][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:43.916 -> [ 26799][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:43.916 -> [ 26807][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:43.916 -> [ 26817][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:43.948 -> [ 26827][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:43.948 -> [ 26835][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:43.948 -> [ 26842][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:43.948 -> [ 26850][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:24:43.980 -> [ 26859][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:43.980 -> [ 26867][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x8, 1
19:24:43.980 -> [ 26874][D][BluetoothA2DPSink.cpp:579] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_METADATA_RSP_EVT
19:24:43.980 -> [ 26884][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:44.012 -> [ 26894][D][BluetoothA2DPSink.cpp:482] app_alloc_meta_buffer(): [BT_AV] app_alloc_meta_buffer
19:24:44.012 -> [ 26902][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:44.012 -> [ 26911][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x2, param len 12
19:24:44.044 -> [ 26921][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:44.044 -> [ 26931][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:44.044 -> [ 26938][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:44.044 -> [ 26953][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:24:44.076 -> [ 26961][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x10, 126
19:24:44.076 -> [ 26971][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x2
19:24:44.076 -> [ 26979][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:44.076 -> [ 26989][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:44.116 -> [ 26997][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:44.116 -> [ 27006][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
9:24:44.116 -> [ 27006][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 2
19:24:44.116 -> [ 27013][I][BluetoothA2DPSink.cpp:965] av_hdl_avrc_evt(): [BT_AV] AVRC metadata rsp: attribute id 0x20,
19:24:45.638 -> [ 28548][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:45.638 -> [ 28548][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
19:24:45.638 -> [ 28553][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 107724
19:24:48.111 -> [ 31033][D][BluetoothA2DPSink.cpp:1313] ccall_app_gap_callback(): [BT_AV] ccall_app_gap_callback
19:24:48.142 -> [ 31033][I][BluetoothA2DPSink.cpp:559] app_gap_callback(): [BT_AV] ESP_BT_GAP_MODE_CHG_EVT
19:24:48.142 -> [ 31038][I][BluetoothA2DPCommon.cpp:246] log_free_heap(): [BT_AV] Available Heap: 107724
19:24:48.143 -> [ 31047][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:48.143 -> [ 31055][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:48.175 -> [ 31063][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:24:48.175 -> [ 31072][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:48.175 -> [ 31079][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:24:48.175 -> [ 31079][D][BluetoothA2DPSink.cpp:1301] ccall_app_a2d_callback(): [BT_AV] ccall_app_a2d_callback
19:24:48.207 -> [ 31088][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:48.207 -> [ 31096][D][BluetoothA2DPSink.cpp:1088] app_a2d_callback(): [BT_AV] app_a2d_callback
19:24:48.207 -> [ 31106][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:48.207 -> [ 31114][D][BluetoothA2DPSink.cpp:1096] app_a2d_callback(): [BT_AV] app_a2d_callback ESP_A2D_AUDIO_STATE_EVT
19:24:48.239 -> [ 31122][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:48.239 -> [ 31131][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x1, param len 16
19:24:48.239 -> [ 31140][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
19:24:48.271 -> [ 31150][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:48.271 -> [ 31158][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 100%
19:24:48.271 -> [ 31168][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.271 -> [ 31174][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 100
19:24:48.303 -> [ 31182][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.303 -> [ 31193][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x1
19:24:48.303 -> [ 31204][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.303 -> [ 31210][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:48.335 -> [ 31218][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.335 -> [ 31228][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:48.303 -> [ 31204][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.303 -> [ 31210][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:48.335 -> [ 31218][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.335 -> [ 31228][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:48.335 -> [ 31239][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.367 -> [ 31245][D][BluetoothA2DPSink.cpp:1333] ccall_av_hdl_a2d_evt(): [BT_AV] ccall_av_hdl_a2d_evt
19:24:48.367 -> [ 31253][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.367 -> [ 31261][D][BluetoothA2DPSink.cpp:619] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt evt 1
19:24:48.367 -> [ 31272][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.399 -> [ 31277][D][BluetoothA2DPSink.cpp:628] av_hdl_a2d_evt(): [BT_AV] av_hdl_a2d_evt ESP_A2D_AUDIO_STATE_EVT
19:24:48.399 -> [ 31285][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.399 -> [ 31294][D][BluetoothA2DPSink.cpp:710] handle_audio_state(): [BT_AV] handle_audio_state evt 1
19:24:48.399 -> [ 31305][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.431 -> [ 31311][I][BluetoothA2DPSink.cpp:712] handle_audio_state(): [BT_AV] A2DP audio state: Started
19:24:48.431 -> [ 31319][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.431 -> Started
19:24:48.431 -> [ 31338][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.431 -> [ 31346][I][BluetoothA2DPSink.cpp:724] handle_audio_state(): [BT_AV] i2s_start
19:24:48.463 -> [ 31347][E][BluetoothA2DPSink.cpp:1344] i2s_write_data(): [BT_AV] i2s_write_data failed - inactive
19:24:48.463 -> [ 31360][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:24:48.463 -> [ 31369][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:24:48.463 -> [ 31378][D][BluetoothA2DPSink.cpp:592] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback ESP_AVRC_CT_CHANGE_NOTIFY_EVT
19:24:48.495 -> [ 31387][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x4, param len 12
19:24:48.495 -> [ 31397][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:48.495 -> [ 31404][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x4
19:24:48.527 -> [ 31412][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:48.527 -> [ 31407][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.527 -> [ 31422][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:48.527 -> [ 31438][D][BluetoothA2DPSink.cpp:1326] ccall_av_hdl_avrc_evt(): [BT_AV] ccall_av_hdl_avrc_evt
19:24:48.559 -> [ 31445][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.559 -> [ 31447][D][BluetoothA2DPSink.cpp:935] av_hdl_avrc_evt(): [BT_AV] av_hdl_avrc_evt evt 4
19:24:48.559 -> [ 31462][D][BluetoothA2DPSink.cpp:911] av_notify_evt_handler(): [BT_AV] av_notify_evt_handler
19:24:48.559 -> [ 31470][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.595 -> [ 31471][D][BluetoothA2DPSink.cpp:918] av_notify_evt_handler(): [BT_AV] av_notify_evt_handler ESP_AVRC_RN_PLAY_STATUS_CHANGE 1, to 1
19:24:48.595 -> [ 31490][D][BluetoothA2DPSink.cpp:896] av_playback_changed(): [BT_AV] av_playback_changed
19:24:48.595 -> [ 31495][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:48.627 -> [ 31522][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
--- Volume lowered, manually,  from 100 to 0. Only at 0 does the device respond by silencing the audio ---
19:24:57.760 -> [ 40664][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.792 -> [ 40687][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.792 -> [ 40709][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:57.792 -> [ 40709][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:57.824 -> [ 40714][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:24:57.824 -> [ 40724][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:57.824 -> [ 40731][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:24:57.856 -> [ 40732][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:57.856 -> [ 40739][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:57.856 -> [ 40748][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:57.856 -> [ 40758][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:57.888 -> [ 40766][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:24:57.888 -> [ 40774][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:57.888 -> [ 40784][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:57.888 -> [ 40793][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
19:24:57.920 -> [ 40802][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.920 -> [ 40808][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 93%
19:24:57.920 -> [ 40825][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 93
19:24:57.920 -> [ 40832][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.952 -> [ 40835][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:24:57.952 -> [ 40852][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:57.952 -> [ 40860][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.984 -> [ 40862][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:57.984 -> [ 40878][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:57.984 -> [ 40886][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:57.984 -> [ 40887][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
19:24:58.016 -> [ 40903][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 0%
19:24:58.016 -> [ 40910][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.016 -> [ 40912][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 0
19:24:58.016 -> [ 40936][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.056 -> [ 40960][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
...
19:24:58.296 -> [ 41192][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.297 -> [ 41216][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.329 -> [ 41237][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:24:58.329 -> [ 41237][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:24:58.329 -> [ 41243][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:24:58.361 -> [ 41252][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:24:58.361 -> [ 41259][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:24:58.361 -> [ 41261][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.361 -> [ 41267][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:24:58.393 -> [ 41285][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched
19:24:58.393 -> [ 41291][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.393 -> [ 41293][D][BluetoothA2DPSink.cpp:1449] ccall_av_hdl_avrc_tg_evt(): [BT_AV] ccall_av_hdl_avrc_tg_evt
19:24:58.425 -> [ 41310][D][BluetoothA2DPSink.cpp:1457] av_hdl_avrc_tg_evt(): [BT_AV] av_hdl_avrc_tg_evt evt 3
19:24:58.425 -> [ 41317][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.425 -> [ 41319][I][BluetoothA2DPSink.cpp:1473] av_hdl_avrc_tg_evt(): [BT_AV] AVRC remote set absolute volume: 5%
19:24:58.425 -> [ 41336][I][BluetoothA2DPSink.cpp:1413] volume_set_by_controller(): [BT_AV] Volume is set by remote controller to 5
19:24:58.460 -> [ 41341][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:24:58.460 -> [ 41369][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
...
19:25:01.581 -> [ 44470][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
19:25:01.581 -> [ 44493][D][BluetoothA2DPSink.cpp:1121] audio_data_callback(): [BT_AV] audio_data_callback
---- Pause is pressed ----
19:25:01.614 -> [ 44516][D][BluetoothA2DPSink.cpp:1443] ccall_app_rc_tg_callback(): [BT_AV] ccall_app_rc_tg_callback
19:25:01.614 -> [ 44517][D][BluetoothA2DPSink.cpp:1393] app_rc_tg_callback(): [BT_AV] app_rc_tg_callback
19:25:01.614 -> [ 44522][D][BluetoothA2DPSink.cpp:363] app_work_dispatch(): [BT_API] app_work_dispatch event 0x3, param len 12
19:25:01.646 -> [ 44532][D][BluetoothA2DPSink.cpp:395] app_send_msg(): [BT_AV] app_send_msg
19:25:01.646 -> [ 44539][D][BluetoothA2DPSink.cpp:418] app_task_handler(): [BT_API] app_task_handler, sig 0x1, 0x3
19:25:01.646 -> [ 44539][D][BluetoothA2DPSink.cpp:1307] ccall_app_rc_ct_callback(): [BT_AV] ccall_app_rc_ct_callback
19:25:01.646 -> [ 44547][I][BluetoothA2DPSink.cpp:421] app_task_handler(): [BT_API] app_task_handler, APP_SIG_WORK_DISPATCH sig: 1
19:25:01.678 -> [ 44556][D][BluetoothA2DPSink.cpp:575] app_rc_ct_callback(): [BT_AV] app_rc_ct_callback
19:25:01.678 -> [ 44566][D][BluetoothA2DPSink.cpp:386] app_work_dispatched(): [BT_AV] app_work_dispatched