Chimes are quite slow from https

Flavor

MicroWakeWord

Checklist

[X] This issue only contains 1 issue (if you have multiple issues, open one issue for each issue).
[X] This issue is not a duplicate issue of currently previous issues..
[X] I am able to consult debug logging for my ESPHome instance.

Describe the issue

Current config loads the error, timer finished and wakeup chimes from github by https. The result is a quite long wait time until it plays the sound. I tried my luck with some tinkering, like:

Adding CONFIG_ESP32S3_DEFAULT_CPU_FREQ_240: "y"
Commenting out codec: auto

But both did not really change anything at all. Then I had the idea that https is quite heavy, too and the S3 is not that of a super computer tbh. I move the three files to my HA www folder and changed the three variables to:

  wakeup_sound_url: "http://192.168.181.42:8123/local/wakeup.mp3"
  error_sound_url: "http://192.168.181.42:8123/local/error.mp3"
  timer_finished_sound_url: "http://192.168.181.42:8123/local/timer_finished.mp3"

The difference is from several seconds to wait down to almost instant playback. Of course you just could use homeassistant.local instead of the static IP here, but I chose to skip the DNS, too.

Here two videos. One with HTTPS and one with HTTP: https://photos.app.goo.gl/rSeHgzJCGFNnrnFU7

As long as we have no way to get the chimes into the firmware to run them locally, maybe a lil howto move the sounds to your HA instance as unencrypted host would be a good idea?

Reproduction steps

Not much to do, get the current config on your Onju and start talking.

Debug logs

Local HTTP:
INFO ESPHome 2024.7.3
INFO Reading configuration /config/esphome/onju-voice-e27688.yaml...
INFO Starting log output from 192.168.181.103 using esphome API
INFO Successfully connected to onju-voice-e27688 @ 192.168.181.103 in 0.110s
INFO Successful handshake with onju-voice-e27688 @ 192.168.181.103 in 0.051s
[13:17:54][I][app:100]: ESPHome version 2024.7.3 compiled on Aug 10 2024, 07:53:16
[13:17:54][I][app:102]: Project tetele.onju_voice_satellite version 1.1.0
[13:17:54][C][wifi:599]: WiFi:
[13:17:54][C][wifi:427]:   Local MAC: 24:58:7C:E2:76:88
[13:17:54][C][wifi:432]:   SSID: 'Asgaard'[redacted]
[13:17:54][C][wifi:435]:   IP Address: 192.168.181.103
[13:17:54][C][wifi:439]:   BSSID: B0:F2:08:55:4B:1E[redacted]
[13:17:54][C][wifi:440]:   Hostname: 'onju-voice-e27688'
[13:17:54][C][wifi:442]:   Signal strength: -53 dB ▂▄▆█
[13:17:54][C][wifi:446]:   Channel: 13
[13:17:54][C][wifi:447]:   Subnet: 255.255.255.0
[13:17:54][C][wifi:448]:   Gateway: 192.168.181.254
[13:17:54][C][wifi:449]:   DNS1: 192.168.181.42
[13:17:54][C][wifi:450]:   DNS2: 0.0.0.0
[13:17:54][C][logger:185]: Logger:
[13:17:54][C][logger:186]:   Level: DEBUG
[13:17:54][C][logger:188]:   Log Baud Rate: 115200
[13:17:54][C][logger:189]:   Hardware UART: USB_SERIAL_JTAG
[13:17:54][C][template.number:050]: Template Number 'Touch threshold percentage'
[13:17:54][C][template.number:051]:   Optimistic: YES
[13:17:54][C][template.number:052]:   Update Interval: never
[13:17:54][C][esp32_rmt_led_strip:175]: ESP32 RMT LED Strip:
[13:17:54][C][esp32_rmt_led_strip:176]:   Pin: 11
[13:17:54][C][esp32_rmt_led_strip:177]:   Channel: 0
[13:17:54][C][esp32_rmt_led_strip:202]:   RGB Order: GRB
[13:17:54][C][esp32_rmt_led_strip:203]:   Max refresh rate: 0
[13:17:54][C][esp32_rmt_led_strip:204]:   Number of LEDs: 6
[13:17:54][C][switch.gpio:068]: GPIO Switch 'dac_mute'
[13:17:54][C][switch.gpio:091]:   Restore Mode: always OFF
[13:17:54][C][switch.gpio:031]:   Pin: GPIO21
[13:17:54][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Disable wake word'
[13:17:54][C][gpio.binary_sensor:016]:   Pin: GPIO38
[13:17:54][C][light:103]: Light 'leds'
[13:17:54][C][light:105]:   Default Transition Length: 0.0s
[13:17:54][C][light:106]:   Gamma Correct: 2.80
[13:17:54][C][light:103]: Light 'left_led'
[13:17:54][C][light:105]:   Default Transition Length: 0.1s
[13:17:54][C][light:106]:   Gamma Correct: 2.80
[13:17:54][C][light:103]: Light 'top_led'
[13:17:54][C][light:105]:   Default Transition Length: 0.1s
[13:17:54][C][light:106]:   Gamma Correct: 2.80
[13:17:54][C][light:103]: Light 'right_led'
[13:17:54][C][light:105]:   Default Transition Length: 0.1s
[13:17:54][C][light:106]:   Gamma Correct: 2.80
[13:17:54][C][template.switch:068]: Template Switch 'Use Wake Word'
[13:17:54][C][template.switch:091]:   Restore Mode: restore defaults to ON
[13:17:54][C][template.switch:057]:   Optimistic: YES
[13:17:54][C][template.switch:068]: Template Switch 'Wake Word Listening Light'
[13:17:54][C][template.switch:091]:   Restore Mode: restore defaults to ON
[13:17:54][C][template.switch:057]:   Optimistic: YES
[13:17:54][C][psram:020]: PSRAM:
[13:17:54][C][psram:021]:   Available: YES
[13:17:54][C][psram:024]:   Size: 8191 KB
[13:17:54][C][i2s_audio:028]: I2SController:
[13:17:54][C][i2s_audio:029]:   AccessMode: duplex
[13:17:54][C][i2s_audio:030]:   Port: 0
[13:17:54][C][i2s_audio:032]:   Reader registered.
[13:17:54][C][i2s_audio:035]:   Writer registered.
[13:17:54][C][i2s_audio:139]: I2S-Writer (Fixed-CFG):
[13:17:54][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[13:17:54][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[13:17:54][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[13:17:54][C][i2s_audio:136]: I2S-Reader (Fixed-CFG):
[13:17:54][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[13:17:54][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[13:17:54][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[13:17:54][C][esp32_touch:073]: Config for ESP32 Touch Hub:
[13:17:54][C][esp32_touch:074]:   Meas cycle: 0.80ms
[13:17:54][C][esp32_touch:075]:   Sleep cycle: 2.00ms
[13:17:54][C][esp32_touch:095]:   Low Voltage Reference: 0.8V
[13:17:54][C][esp32_touch:115]:   High Voltage Reference: 2.4V
[13:17:54][C][esp32_touch:135]:   Voltage Attenuation: 0V
[13:17:54][C][esp32_touch:169]:   Filter mode: IIR_16
[13:17:54][C][esp32_touch:170]:   Debounce count: 2
[13:17:54][C][esp32_touch:171]:   Noise threshold coefficient: 0
[13:17:54][C][esp32_touch:172]:   Jitter filter step size: 0
[13:17:54][C][esp32_touch:191]:   Smooth level: IIR_2
[13:17:54][C][esp32_touch:213]:   Denoise grade: BIT8
[13:17:54][C][esp32_touch:245]:   Denoise capacitance level: L0
[13:17:54][C][esp32_touch:260]:   Touch Pad 'volume_down'
[13:17:54][C][esp32_touch:261]:     Pad: T4
[13:17:54][C][esp32_touch:262]:     Threshold: 338909
[13:17:54][C][esp32_touch:260]:   Touch Pad 'volume_up'
[13:17:54][C][esp32_touch:261]:     Pad: T2
[13:17:54][C][esp32_touch:262]:     Threshold: 369629
[13:17:54][C][esp32_touch:260]:   Touch Pad 'action'
[13:17:54][C][esp32_touch:261]:     Pad: T3
[13:17:54][C][esp32_touch:262]:     Threshold: 488037
[13:17:54][C][captive_portal:088]: Captive Portal:
[13:17:54][C][mdns:116]: mDNS:
[13:17:54][C][mdns:117]:   Hostname: onju-voice-e27688
[13:17:54][C][esphome.ota:073]: Over-The-Air updates:
[13:17:54][C][esphome.ota:074]:   Address: onju-voice-e27688.local:3232
[13:17:54][C][esphome.ota:075]:   Version: 2
[13:17:54][C][safe_mode:018]: Safe Mode:
[13:17:54][C][safe_mode:020]:   Boot considered successful after 60 seconds
[13:17:54][C][safe_mode:021]:   Invoke after 10 boot attempts
[13:17:54][C][safe_mode:023]:   Remain in safe mode for 300 seconds
[13:17:54][C][api:139]: API Server:
[13:17:54][C][api:140]:   Address: onju-voice-e27688.local:6053
[13:17:54][C][api:142]:   Using noise encryption: YES
[13:17:54][C][improv_serial:032]: Improv Serial:
[13:17:54][C][micro_wake_word:051]: microWakeWord:
[13:17:54][C][micro_wake_word:052]:   models:
[13:17:54][C][micro_wake_word:015]:     - Wake Word: okay nabu
[13:17:54][C][micro_wake_word:016]:       Probability cutoff: 0.970
[13:17:54][C][micro_wake_word:017]:       Sliding window size: 5
[13:17:54][C][esp_adf_pipeline.microphone:020]: ADF-Microphone
[13:17:54][C][adf_media_player:016]: ESP-ADF-MediaPlayer:
[13:17:54][C][adf_media_player:018]:   MP_ANNOUNCE enabled
[13:17:54][C][adf_media_player:024]:   Number of ADFComponents: 3
[13:18:12][D][micro_wake_word:162]: The 'okay nabu' model sliding average probability is 0.994 and most recent probability is 1.000
[13:18:12][D][micro_wake_word:123]: Wake Word 'okay nabu' Detected
[13:18:12][D][micro_wake_word:195]: State changed from DETECTING_WAKE_WORD to STOP_MICROPHONE
[13:18:12][D][micro_wake_word:129]: Stopping Microphone
[13:18:12][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[13:18:12][D][micro_wake_word:195]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[13:18:12][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[13:18:12][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[13:18:12][D][esp-idf:000][resampler]: W (19449309) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT

[13:18:12][D][esp-idf:000][i2s_in]: W (19449313) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT

[13:18:12][D][esp-idf:000][i2s_in]: W (19449315) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT

[13:18:12][D][esp-idf:000][i2s_in]: W (19449318) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT

[13:18:12][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[13:18:12][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[13:18:12][D][micro_wake_word:195]: State changed from STOPPING_MICROPHONE to IDLE
[13:18:12][D][media_player:061]: 'Onju 1' - Setting
[13:18:12][D][media_player:068]:   Media URL: http://192.168.181.42:8123/local/wakeup.mp3
[13:18:12][D][esp_audio_sources:098]: Set new uri: http://192.168.181.42:8123/local/wakeup.mp3
[13:18:12][D][adf_media_player:057]: Got control call in state IDLE
[13:18:12][D][adf_media_player:058]: req_track stream uri: http://192.168.181.42:8123/local/wakeup.mp3
[13:18:12][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:18:12][D][voice_assistant:504]: State changed from IDLE to START_MICROPHONE
[13:18:12][D][voice_assistant:510]: Desired state set to START_PIPELINE
[13:18:12][D][voice_assistant:221]: Starting Microphone
[13:18:12][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[13:18:12][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:18:13][D][voice_assistant:504]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[13:18:13][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:18:13][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:18:13][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[13:18:13][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:18:13][D][esp_audio_processors:124]: Current settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:13][I][adf_media_player:256]: current mp state: PLAYING
[13:18:13][I][adf_media_player:257]: anouncement: false
[13:18:13][I][adf_media_player:258]: play_intent: false
[13:18:13][I][adf_media_player:259]: current_uri_: yes
[13:18:13][D][adf_audio_element:108]: Preparing [i2s_in]...
[13:18:13][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[13:18:13][D][esp_audio_sources:137]: Use fixed settings: no
[13:18:13][D][esp_audio_sources:138]: Streamer status: 6
[13:18:13][D][esp_audio_sources:139]: decoder status: 6
[13:18:13][D][esp_audio_sources:140]: stream uri: http://192.168.181.42:8123/local/wakeup.mp3
[13:18:13][D][adf_audio_element:108]: Preparing [http]...
[13:18:13][D][adf_audio_element:108]: Preparing [decoder]...
[13:18:13][D][adf_audio_element:108]: Preparing [resampler]...
[13:18:13][D][adf_audio_element:108]: Preparing [resampler]...
[13:18:13][D][adf_audio_element:108]: Preparing [pcm_reader]...
[13:18:13][D][adf_audio_element:108]: Preparing [i2s_out]...
[13:18:13][D][esp_audio_sinks:054]: Set bitdepth to 32
[13:18:13][D][esp_audio_sinks:054]: Set bitdepth to 16
[13:18:13][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[13:18:13][D][esp_adf_pipeline:342]: wait for preparation, done
[13:18:13][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:18:13][D][adf_audio_element:165]: Resuming [i2s_in]...
[13:18:13][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[13:18:13][D][esp-idf:000][i2s_in]: I (19449480) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1

[13:18:13][D][esp-idf:000][resampler]: I (19449483) AUDIO_ELEMENT: [resampler] AEL_MSG_CMD_RESUME,state:1

[13:18:13][D][adf_audio_element:191]: [resampler] Checking State, got 79
[13:18:13][D][esp-idf:000][resampler]: I (19449494) RSP_FILTER: sample rate of source data : 48000, channel of source data : 1, sample rate of destination data : 16000, channel of destination data : 1

[13:18:13][D][voice_assistant:275]: Requesting start...
[13:18:13][D][voice_assistant:504]: State changed from START_PIPELINE to STARTING_PIPELINE
[13:18:13][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[13:18:13][D][voice_assistant:525]: Client started, streaming microphone
[13:18:13][D][voice_assistant:504]: State changed from STARTING_PIPELINE to STREAMING_MICROPHONE
[13:18:13][D][voice_assistant:510]: Desired state set to STREAMING_MICROPHONE
[13:18:13][D][voice_assistant:627]: Event Type: 1
[13:18:13][D][voice_assistant:630]: Assist Pipeline running
[13:18:13][D][adf_audio_element:191]: [http] Checking State, got 79
[13:18:13][D][adf_audio_element:191]: [decoder] Checking State, got 79
[13:18:13][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:18:13][D][voice_assistant:627]: Event Type: 3
[13:18:13][D][voice_assistant:641]: STT started
[13:18:13][D][light:036]: 'top_led' Setting:
[13:18:13][D][light:051]:   Brightness: 100%
[13:18:13][D][light:059]:   Red: 100%, Green: 100%, Blue: 100%
[13:18:13][D][light:109]:   Effect: 'listening'
[13:18:13][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=2
[13:18:13][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=192000, duration=0, bytes=-93
[13:18:13][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:18:13][D][esp_audio_processors:108]: Received request from: HTTPStreamReader
[13:18:13][D][esp_audio_processors:113]: New settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:13][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:13][D][adf_audio_element:108]: Preparing [http]...
[13:18:13][D][adf_audio_element:108]: Preparing [decoder]...
[13:18:13][D][esp-idf:000][decoder]: W (19449625) AUDIO_ELEMENT: OUT-[decoder] AEL_IO_ABORT

[13:18:13][D][esp-idf:000][decoder]: W (19449628) MP3_DECODER: output aborted -3

[13:18:13][D][esp-idf:000][decoder]: I (19449635) MP3_DECODER: Closed

[13:18:13][D][esp_audio_sources:193]: Preparation done!
[13:18:13][D][esp_adf_pipeline:342]: wait for preparation, done
[13:18:13][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:18:13][I][adf_media_player:192]: got new pipeline state: 5, while in MP state PLAYING
[13:18:13][I][adf_media_player:256]: current mp state: PLAYING
[13:18:13][I][adf_media_player:257]: anouncement: false
[13:18:13][I][adf_media_player:258]: play_intent: false
[13:18:13][I][adf_media_player:259]: current_uri_: yes
[13:18:13][D][adf_audio_element:165]: Resuming [http]...
[13:18:13][D][adf_audio_element:172]: [http] Sending resume command.
[13:18:13][D][adf_audio_element:165]: Resuming [decoder]...
[13:18:13][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:18:13][D][adf_audio_element:165]: Resuming [resampler]...
[13:18:13][D][adf_audio_element:172]: [resampler] Sending resume command.
[13:18:13][D][esp-idf:000][resampler]: I (19449696) AUDIO_ELEMENT: [resampler] AEL_MSG_CMD_RESUME,state:1

[13:18:13][D][adf_audio_element:165]: Resuming [i2s_out]...
[13:18:13][D][adf_audio_element:172]: [i2s_out] Sending resume command.
[13:18:13][D][esp-idf:000][i2s_out]: I (19449707) AUDIO_ELEMENT: [i2s_out] AEL_MSG_CMD_RESUME,state:1

[13:18:13][D][adf_audio_element:191]: [resampler] Checking State, got 79
[13:18:13][D][adf_audio_element:191]: [i2s_out] Checking State, got 75
[13:18:13][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:18:13][I][adf_media_player:192]: got new pipeline state: 6, while in MP state PLAYING
[13:18:13][I][adf_media_player:256]: current mp state: PLAYING
[13:18:13][I][adf_media_player:257]: anouncement: false
[13:18:13][I][adf_media_player:258]: play_intent: false
[13:18:13][I][adf_media_player:259]: current_uri_: yes
[13:18:13][I][esp_adf_pipeline:132]: [ i2s_out ] status: 12
[13:18:13][D][esp-idf:000][resampler]: I (19449850) RSP_FILTER: sample rate of source data : 44100, channel of source data : 2, sample rate of destination data : 48000, channel of destination data : 1

[13:18:13][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[13:18:13][I][esp_audio_sources:033][http]: Receive http event: 2
[13:18:13][I][esp_audio_sources:033][http]: Receive http event: 4
[13:18:13][D][esp-idf:000][http]: I (19449881) HTTP_CLIENT: Body received in fetch header state, 0x3fcc5582, 1702

[13:18:13][D][esp-idf:000][http]: I (19449887) HTTP_STREAM: total_bytes=19527

[13:18:13][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:18:13][I][esp_adf_pipeline:132]: [ http ] status: 12
[13:18:13][I][esp_adf_pipeline:132]: [ decoder ] status: 12
[13:18:13][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=2
[13:18:13][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=192000, duration=0, bytes=-93
[13:18:14][D][esp-idf:000][http]: W (19450399) HTTP_STREAM: No more data,errno:0, total_bytes:19527, rlen = 0

[13:18:14][I][esp_audio_sources:033][http]: Receive http event: 7
[13:18:14][D][esp-idf:000][http]: I (19450411) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0

[13:18:14][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 19527
[13:18:14][I][esp_adf_pipeline:132]: [ http ] status: 15
[13:18:14][I][esp_adf_pipeline:135]: current state: RUNNING
[13:18:14][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[13:18:14][I][adf_media_player:192]: got new pipeline state: 7, while in MP state PLAYING
[13:18:14][I][adf_media_player:256]: current mp state: PLAYING
[13:18:14][I][adf_media_player:257]: anouncement: false
[13:18:14][I][adf_media_player:258]: play_intent: false
[13:18:14][I][adf_media_player:259]: current_uri_: yes
[13:18:14][D][esp-idf:000][decoder]: I (19450547) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2

[13:18:14][D][esp-idf:000][decoder]: I (19450643) MP3_DECODER: Closed

[13:18:14][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -93
[13:18:14][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[13:18:14][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:14][D][esp-idf:000][resampler]: I (19450673) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2

[13:18:14][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[13:18:14][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:14][D][esp-idf:000][i2s_out]: I (19450699) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2

[13:18:14][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[13:18:14][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[13:18:14][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:14][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[13:18:14][I][adf_media_player:192]: got new pipeline state: 4, while in MP state PLAYING
[13:18:14][I][adf_media_player:256]: current mp state: IDLE
[13:18:14][I][adf_media_player:257]: anouncement: false
[13:18:14][I][adf_media_player:258]: play_intent: false
[13:18:14][I][adf_media_player:259]: current_uri_: yes
[13:18:15][D][voice_assistant:627]: Event Type: 11
[13:18:15][D][voice_assistant:783]: Starting STT by VAD
[13:18:17][D][voice_assistant:627]: Event Type: 12
[13:18:17][D][voice_assistant:787]: STT by VAD end
[13:18:17][D][voice_assistant:504]: State changed from STREAMING_MICROPHONE to STOP_MICROPHONE
[13:18:17][D][voice_assistant:510]: Desired state set to AWAITING_RESPONSE
[13:18:17][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[13:18:17][D][voice_assistant:504]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[13:18:17][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[13:18:17][D][light:036]: 'top_led' Setting:
[13:18:17][D][light:051]:   Brightness: 70%
[13:18:17][D][light:059]:   Red: 0%, Green: 20%, Blue: 100%
[13:18:17][D][light:109]:   Effect: 'processing'
[13:18:17][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[13:18:17][D][esp-idf:000][resampler]: W (19454361) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT

[13:18:17][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[13:18:17][D][voice_assistant:504]: State changed from STOPPING_MICROPHONE to AWAITING_RESPONSE
[13:18:18][D][voice_assistant:627]: Event Type: 4
[13:18:18][D][voice_assistant:655]: Speech recognised as: "Hallo. Dies ist ein Test. Ja."
[13:18:18][D][voice_assistant:627]: Event Type: 5
[13:18:18][D][voice_assistant:660]: Intent started
[13:18:20][D][voice_assistant:627]: Event Type: 6
[13:18:20][D][voice_assistant:627]: Event Type: 7
[13:18:20][D][voice_assistant:683]: Response: "Ach wirklich? Ein Test? Das ist ja revolutionär. Lass mal sehen, was du so testen möchtest."
[13:18:20][D][voice_assistant:627]: Event Type: 8
[13:18:20][D][voice_assistant:705]: Response URL: "http://192.168.181.42:8123/api/tts_proxy/2aab80dbcc1a403a533bbfb8f1f94e71065c3164_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3"
[13:18:20][D][voice_assistant:504]: State changed from AWAITING_RESPONSE to STREAMING_RESPONSE
[13:18:20][D][voice_assistant:510]: Desired state set to STREAMING_RESPONSE
[13:18:20][D][media_player:061]: 'Onju 1' - Setting
[13:18:20][D][media_player:068]:   Media URL: http://192.168.181.42:8123/api/tts_proxy/2aab80dbcc1a403a533bbfb8f1f94e71065c3164_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:18:20][D][media_player:074]:  Announcement: yes
[13:18:20][D][adf_media_player:057]: Got control call in state IDLE
[13:18:20][D][adf_media_player:058]: req_track stream uri: http://192.168.181.42:8123/api/tts_proxy/2aab80dbcc1a403a533bbfb8f1f94e71065c3164_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:18:20][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:18:20][D][light:036]: 'top_led' Setting:
[13:18:20][D][light:059]:   Red: 20%, Green: 100%, Blue: 0%
[13:18:20][D][light:109]:   Effect: 'speaking'
[13:18:20][D][voice_assistant:627]: Event Type: 2
[13:18:20][D][voice_assistant:719]: Assist Pipeline ended
[13:18:20][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:18:20][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[13:18:20][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:18:20][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:20][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:18:20][I][adf_media_player:257]: anouncement: yes
[13:18:20][I][adf_media_player:258]: play_intent: false
[13:18:20][I][adf_media_player:259]: current_uri_: yes
[13:18:20][D][light:036]: 'top_led' Setting:
[13:18:20][D][light:051]:   Brightness: 60%
[13:18:20][D][light:059]:   Red: 100%, Green: 0%, Blue: 100%
[13:18:20][D][light:109]:   Effect: 'listening_ww'
[13:18:20][D][micro_wake_word:399]: Resetting buffers and probabilities
[13:18:20][D][micro_wake_word:195]: State changed from IDLE to START_MICROPHONE
[13:18:20][D][micro_wake_word:107]: Starting Microphone
[13:18:20][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[13:18:20][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:18:20][D][micro_wake_word:195]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[13:18:20][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:18:20][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[13:18:20][D][esp_audio_sources:137]: Use fixed settings: no
[13:18:20][D][esp_audio_sources:138]: Streamer status: 6
[13:18:20][D][esp_audio_sources:139]: decoder status: 6
[13:18:20][D][esp_audio_sources:140]: stream uri: http://192.168.181.42:8123/api/tts_proxy/2aab80dbcc1a403a533bbfb8f1f94e71065c3164_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:18:20][D][adf_audio_element:108]: Preparing [http]...
[13:18:20][D][adf_audio_element:108]: Preparing [decoder]...
[13:18:20][D][adf_audio_element:108]: Preparing [i2s_in]...
[13:18:20][D][adf_audio_element:108]: Preparing [resampler]...
[13:18:20][D][adf_audio_element:108]: Preparing [resampler]...
[13:18:20][D][adf_audio_element:108]: Preparing [i2s_out]...
[13:18:20][D][adf_audio_element:108]: Preparing [pcm_reader]...
[13:18:20][D][esp_audio_sinks:054]: Set bitdepth to 32
[13:18:20][D][esp_audio_sinks:054]: Set bitdepth to 16
[13:18:20][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[13:18:20][D][esp_adf_pipeline:342]: wait for preparation, done
[13:18:20][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:18:20][D][adf_audio_element:165]: Resuming [http]...
[13:18:20][D][adf_audio_element:172]: [http] Sending resume command.
[13:18:20][D][adf_audio_element:165]: Resuming [decoder]...
[13:18:20][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:18:20][D][esp-idf:000][decoder]: I (19456742) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1

[13:18:20][D][esp-idf:000][decoder]: I (19456765) MP3_DECODER: MP3 opened

[13:18:20][D][adf_audio_element:191]: [http] Checking State, got 79
[13:18:20][D][adf_audio_element:191]: [decoder] Checking State, got 79
[13:18:20][D][esp-idf:000][http]: I (19457166) HTTP_CLIENT: Body received in fetch header state, 0x3fcc904b, 1841

[13:18:20][D][esp-idf:000][http]: I (19457171) HTTP_STREAM: total_bytes=60293

[13:18:20][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:18:20][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=24000, bits=16, ch=1
[13:18:20][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=48000, duration=0, bytes=-1253
[13:18:20][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:18:20][D][esp_audio_processors:108]: Received request from: HTTPStreamReader
[13:18:20][D][esp_audio_processors:113]: New settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:20][D][esp_audio_processors:124]: Current settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:18:20][D][adf_audio_element:108]: Preparing [http]...
[13:18:20][D][adf_audio_element:108]: Preparing [decoder]...
[13:18:20][D][esp-idf:000][decoder]: W (19457235) AUDIO_ELEMENT: OUT-[decoder] AEL_IO_ABORT

[13:18:20][D][esp-idf:000][decoder]: W (19457240) MP3_DECODER: output aborted -3

[13:18:20][D][esp-idf:000][decoder]: I (19457245) MP3_DECODER: Closed

[13:18:20][D][esp_audio_sources:193]: Preparation done!
[13:18:20][D][esp_adf_pipeline:342]: wait for preparation, done
[13:18:20][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:18:20][I][adf_media_player:192]: got new pipeline state: 5, while in MP state ANNOUNCING
[13:18:20][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:18:20][I][adf_media_player:257]: anouncement: yes
[13:18:20][I][adf_media_player:258]: play_intent: false
[13:18:20][I][adf_media_player:259]: current_uri_: yes
[13:18:21][D][adf_audio_element:165]: Resuming [http]...
[13:18:21][D][adf_audio_element:172]: [http] Sending resume command.
[13:18:21][D][adf_audio_element:165]: Resuming [decoder]...
[13:18:21][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:18:21][D][esp-idf:000][decoder]: I (19457405) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1

[13:18:21][D][esp-idf:000][decoder]: I (19457481) MP3_DECODER: MP3 opened

[13:18:21][I][esp_adf_pipeline:132]: [ decoder ] status: 12
[13:18:21][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=24000, bits=16, ch=1
[13:18:21][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=48000, duration=0, bytes=-1253
[13:18:21][D][adf_audio_element:191]: [http] Checking State, got 78
[13:18:21][D][adf_audio_element:191]: [decoder] Checking State, got 78
[13:18:21][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:18:21][I][adf_media_player:192]: got new pipeline state: 6, while in MP state ANNOUNCING
[13:18:21][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:18:21][I][adf_media_player:257]: anouncement: yes
[13:18:21][I][adf_media_player:258]: play_intent: false
[13:18:21][I][adf_media_player:259]: current_uri_: yes
[13:18:29][D][esp-idf:000][http]: W (19466132) HTTP_STREAM: No more data,errno:0, total_bytes:60293, rlen = 0

[13:18:29][I][esp_audio_sources:033][http]: Receive http event: 7
[13:18:29][D][esp-idf:000][http]: I (19466140) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0

[13:18:29][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 60293
[13:18:29][I][esp_adf_pipeline:132]: [ http ] status: 15
[13:18:29][I][esp_adf_pipeline:135]: current state: RUNNING
[13:18:29][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[13:18:29][I][adf_media_player:192]: got new pipeline state: 7, while in MP state ANNOUNCING
[13:18:29][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:18:29][I][adf_media_player:257]: anouncement: yes
[13:18:29][I][adf_media_player:258]: play_intent: false
[13:18:29][I][adf_media_player:259]: current_uri_: yes
[13:18:30][D][esp-idf:000][decoder]: I (19466834) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2

[13:18:30][D][esp-idf:000][decoder]: I (19467196) MP3_DECODER: Closed

[13:18:30][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -1253
[13:18:30][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[13:18:30][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:30][D][esp-idf:000][resampler]: I (19467301) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2

[13:18:30][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[13:18:30][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:30][D][esp-idf:000][i2s_out]: I (19467333) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2

[13:18:31][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[13:18:31][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[13:18:31][I][esp_adf_pipeline:135]: current state: FINISHING
[13:18:31][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[13:18:31][I][adf_media_player:192]: got new pipeline state: 4, while in MP state ANNOUNCING
[13:18:31][I][adf_media_player:256]: current mp state: IDLE
[13:18:31][I][adf_media_player:257]: anouncement: false
[13:18:31][I][adf_media_player:258]: play_intent: false
[13:18:31][I][adf_media_player:259]: current_uri_: yes
[13:18:33][D][voice_assistant:504]: State changed from STREAMING_RESPONSE to IDLE
[13:18:33][D][voice_assistant:510]: Desired state set to IDLE

GitHib via HTTPS:
INFO ESPHome 2024.7.3
INFO Reading configuration /config/esphome/onju-voice-e27688.yaml...
INFO Starting log output from 192.168.181.103 using esphome API
INFO Successfully connected to onju-voice-e27688 @ 192.168.181.103 in 0.015s
INFO Successful handshake with onju-voice-e27688 @ 192.168.181.103 in 0.059s
[13:27:53][I][app:100]: ESPHome version 2024.7.3 compiled on Aug 10 2024, 13:19:45
[13:27:53][I][app:102]: Project tetele.onju_voice_satellite version 1.1.0
[13:27:53][C][wifi:599]: WiFi:
[13:27:53][C][wifi:427]:   Local MAC: 24:58:7C:E2:76:88
[13:27:53][C][wifi:432]:   SSID: 'Asgaard'[redacted]
[13:27:53][C][wifi:435]:   IP Address: 192.168.181.103
[13:27:53][C][wifi:439]:   BSSID: B0:F2:08:55:4B:1E[redacted]
[13:27:53][C][wifi:440]:   Hostname: 'onju-voice-e27688'
[13:27:53][C][wifi:442]:   Signal strength: -52 dB ▂▄▆█
[13:27:53][C][wifi:446]:   Channel: 13
[13:27:53][C][wifi:447]:   Subnet: 255.255.255.0
[13:27:53][C][wifi:448]:   Gateway: 192.168.181.254
[13:27:53][C][wifi:449]:   DNS1: 192.168.181.42
[13:27:53][C][wifi:450]:   DNS2: 0.0.0.0
[13:27:53][C][logger:185]: Logger:
[13:27:53][C][logger:186]:   Level: DEBUG
[13:27:53][C][logger:188]:   Log Baud Rate: 115200
[13:27:53][C][logger:189]:   Hardware UART: USB_SERIAL_JTAG
[13:27:53][C][template.number:050]: Template Number 'Touch threshold percentage'
[13:27:53][C][template.number:051]:   Optimistic: YES
[13:27:53][C][template.number:052]:   Update Interval: never
[13:27:53][C][esp32_rmt_led_strip:175]: ESP32 RMT LED Strip:
[13:27:53][C][esp32_rmt_led_strip:176]:   Pin: 11
[13:27:53][C][esp32_rmt_led_strip:177]:   Channel: 0
[13:27:53][C][esp32_rmt_led_strip:202]:   RGB Order: GRB
[13:27:53][C][esp32_rmt_led_strip:203]:   Max refresh rate: 0
[13:27:53][C][esp32_rmt_led_strip:204]:   Number of LEDs: 6
[13:27:53][C][switch.gpio:068]: GPIO Switch 'dac_mute'
[13:27:53][C][switch.gpio:091]:   Restore Mode: always OFF
[13:27:53][C][switch.gpio:031]:   Pin: GPIO21
[13:27:54][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Disable wake word'
[13:27:54][C][gpio.binary_sensor:016]:   Pin: GPIO38
[13:27:54][C][light:103]: Light 'leds'
[13:27:54][C][light:105]:   Default Transition Length: 0.0s
[13:27:54][C][light:106]:   Gamma Correct: 2.80
[13:27:54][C][light:103]: Light 'left_led'
[13:27:54][C][light:105]:   Default Transition Length: 0.1s
[13:27:54][C][light:106]:   Gamma Correct: 2.80
[13:27:54][C][light:103]: Light 'top_led'
[13:27:54][C][light:105]:   Default Transition Length: 0.1s
[13:27:54][C][light:106]:   Gamma Correct: 2.80
[13:27:54][C][light:103]: Light 'right_led'
[13:27:54][C][light:105]:   Default Transition Length: 0.1s
[13:27:54][C][light:106]:   Gamma Correct: 2.80
[13:27:54][C][template.switch:068]: Template Switch 'Use Wake Word'
[13:27:54][C][template.switch:091]:   Restore Mode: restore defaults to ON
[13:27:54][C][template.switch:057]:   Optimistic: YES
[13:27:54][C][template.switch:068]: Template Switch 'Wake Word Listening Light'
[13:27:54][C][template.switch:091]:   Restore Mode: restore defaults to ON
[13:27:54][C][template.switch:057]:   Optimistic: YES
[13:27:54][C][psram:020]: PSRAM:
[13:27:54][C][psram:021]:   Available: YES
[13:27:54][C][psram:024]:   Size: 8191 KB
[13:27:54][C][i2s_audio:028]: I2SController:
[13:27:54][C][i2s_audio:029]:   AccessMode: duplex
[13:27:54][C][i2s_audio:030]:   Port: 0
[13:27:54][C][i2s_audio:032]:   Reader registered.
[13:27:54][C][i2s_audio:035]:   Writer registered.
[13:27:54][C][i2s_audio:139]: I2S-Writer (Fixed-CFG):
[13:27:54][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[13:27:54][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[13:27:54][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[13:27:54][C][i2s_audio:136]: I2S-Reader (Fixed-CFG):
[13:27:54][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[13:27:54][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[13:27:54][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[13:27:54][C][esp32_touch:073]: Config for ESP32 Touch Hub:
[13:27:54][C][esp32_touch:074]:   Meas cycle: 0.80ms
[13:27:54][C][esp32_touch:075]:   Sleep cycle: 2.00ms
[13:27:54][C][esp32_touch:095]:   Low Voltage Reference: 0.8V
[13:27:54][C][esp32_touch:115]:   High Voltage Reference: 2.4V
[13:27:54][C][esp32_touch:135]:   Voltage Attenuation: 0V
[13:27:54][C][esp32_touch:169]:   Filter mode: IIR_16
[13:27:54][C][esp32_touch:170]:   Debounce count: 2
[13:27:54][C][esp32_touch:171]:   Noise threshold coefficient: 0
[13:27:54][C][esp32_touch:172]:   Jitter filter step size: 0
[13:27:54][C][esp32_touch:191]:   Smooth level: IIR_2
[13:27:54][C][esp32_touch:213]:   Denoise grade: BIT8
[13:27:54][C][esp32_touch:245]:   Denoise capacitance level: L0
[13:27:54][C][esp32_touch:260]:   Touch Pad 'volume_down'
[13:27:54][C][esp32_touch:261]:     Pad: T4
[13:27:54][C][esp32_touch:262]:     Threshold: 339257
[13:27:54][C][esp32_touch:260]:   Touch Pad 'volume_up'
[13:27:54][C][esp32_touch:261]:     Pad: T2
[13:27:54][C][esp32_touch:262]:     Threshold: 369563
[13:27:54][C][esp32_touch:260]:   Touch Pad 'action'
[13:27:54][C][esp32_touch:261]:     Pad: T3
[13:27:54][C][esp32_touch:262]:     Threshold: 487974
[13:27:54][C][captive_portal:088]: Captive Portal:
[13:27:54][C][mdns:116]: mDNS:
[13:27:54][C][mdns:117]:   Hostname: onju-voice-e27688
[13:27:54][C][esphome.ota:073]: Over-The-Air updates:
[13:27:54][C][esphome.ota:074]:   Address: onju-voice-e27688.local:3232
[13:27:54][C][esphome.ota:075]:   Version: 2
[13:27:54][C][safe_mode:018]: Safe Mode:
[13:27:54][C][safe_mode:020]:   Boot considered successful after 60 seconds
[13:27:54][C][safe_mode:021]:   Invoke after 10 boot attempts
[13:27:54][C][safe_mode:023]:   Remain in safe mode for 300 seconds
[13:27:54][C][api:139]: API Server:
[13:27:54][C][api:140]:   Address: onju-voice-e27688.local:6053
[13:27:54][C][api:142]:   Using noise encryption: YES
[13:27:54][C][improv_serial:032]: Improv Serial:
[13:27:54][C][micro_wake_word:051]: microWakeWord:
[13:27:54][C][micro_wake_word:052]:   models:
[13:27:54][C][micro_wake_word:015]:     - Wake Word: okay nabu
[13:27:54][C][micro_wake_word:016]:       Probability cutoff: 0.970
[13:27:54][C][micro_wake_word:017]:       Sliding window size: 5
[13:27:54][C][esp_adf_pipeline.microphone:020]: ADF-Microphone
[13:27:54][C][adf_media_player:016]: ESP-ADF-MediaPlayer:
[13:27:54][C][adf_media_player:018]:   MP_ANNOUNCE enabled
[13:27:54][C][adf_media_player:024]:   Number of ADFComponents: 3
[13:27:57][D][micro_wake_word:162]: The 'okay nabu' model sliding average probability is 0.978 and most recent probability is 1.000
[13:27:57][D][micro_wake_word:123]: Wake Word 'okay nabu' Detected
[13:27:57][D][micro_wake_word:195]: State changed from DETECTING_WAKE_WORD to STOP_MICROPHONE
[13:27:57][D][micro_wake_word:129]: Stopping Microphone
[13:27:57][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[13:27:57][D][micro_wake_word:195]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[13:27:57][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[13:27:57][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[13:27:57][D][esp-idf:000][resampler]: W (442966) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT

[13:27:57][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[13:27:57][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[13:27:57][D][micro_wake_word:195]: State changed from STOPPING_MICROPHONE to IDLE
[13:27:57][D][media_player:061]: 'Onju 1' - Setting
[13:27:57][D][media_player:068]:   Media URL: https://github.com/tetele/onju-voice-satellite/raw/main/res/wakeup.mp3
[13:27:57][D][esp_audio_sources:098]: Set new uri: https://github.com/tetele/onju-voice-satellite/raw/main/res/wakeup.mp3
[13:27:57][D][adf_media_player:057]: Got control call in state IDLE
[13:27:57][D][adf_media_player:058]: req_track stream uri: https://github.com/tetele/onju-voice-satellite/raw/main/res/wakeup.mp3
[13:27:57][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:27:57][D][voice_assistant:504]: State changed from IDLE to START_MICROPHONE
[13:27:57][D][voice_assistant:510]: Desired state set to START_PIPELINE
[13:27:57][D][voice_assistant:221]: Starting Microphone
[13:27:57][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[13:27:57][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:27:57][D][voice_assistant:504]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[13:27:57][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:27:57][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:27:57][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[13:27:57][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:27:57][D][esp_audio_processors:124]: Current settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:27:57][I][adf_media_player:256]: current mp state: PLAYING
[13:27:57][I][adf_media_player:257]: anouncement: false
[13:27:57][I][adf_media_player:258]: play_intent: false
[13:27:57][I][adf_media_player:259]: current_uri_: yes
[13:27:57][D][adf_audio_element:108]: Preparing [i2s_in]...
[13:27:57][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[13:27:57][D][esp_audio_sources:137]: Use fixed settings: no
[13:27:57][D][esp_audio_sources:138]: Streamer status: 6
[13:27:57][D][esp_audio_sources:139]: decoder status: 6
[13:27:57][D][esp_audio_sources:140]: stream uri: https://github.com/tetele/onju-voice-satellite/raw/main/res/wakeup.mp3
[13:27:57][D][adf_audio_element:108]: Preparing [http]...
[13:27:57][D][adf_audio_element:108]: Preparing [decoder]...
[13:27:57][D][adf_audio_element:108]: Preparing [resampler]...
[13:27:57][D][adf_audio_element:108]: Preparing [resampler]...
[13:27:57][D][adf_audio_element:108]: Preparing [pcm_reader]...
[13:27:57][D][adf_audio_element:108]: Preparing [i2s_out]...
[13:27:57][D][esp_audio_sinks:054]: Set bitdepth to 32
[13:27:57][D][esp_audio_sinks:054]: Set bitdepth to 16
[13:27:57][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[13:27:57][D][esp_adf_pipeline:342]: wait for preparation, done
[13:27:57][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:27:57][D][adf_audio_element:165]: Resuming [i2s_in]...
[13:27:57][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[13:27:57][D][esp-idf:000][i2s_in]: I (443134) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1

[13:27:57][D][adf_audio_element:165]: Resuming [pcm_reader]...
[13:27:57][I][esp_adf_pipeline:132]: [ i2s_in ] status: 12
[13:27:57][D][esp-idf:000][http]: I (443147) AUDIO_ELEMENT: [http] AEL_MSG_CMD_RESUME,state:1

[13:27:57][D][adf_audio_element:191]: [pcm_reader] Checking State, got 65
[13:27:57][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:27:57][D][voice_assistant:504]: State changed from STARTING_MICROPHONE to START_PIPELINE
[13:27:57][D][adf_audio_element:191]: [http] Checking State, got 79
[13:27:57][D][adf_audio_element:191]: [decoder] Checking State, got 79
[13:27:57][D][voice_assistant:275]: Requesting start...
[13:27:57][D][voice_assistant:504]: State changed from START_PIPELINE to STARTING_PIPELINE
[13:27:57][D][voice_assistant:525]: Client started, streaming microphone
[13:27:57][D][voice_assistant:504]: State changed from STARTING_PIPELINE to STREAMING_MICROPHONE
[13:27:57][D][voice_assistant:510]: Desired state set to STREAMING_MICROPHONE
[13:27:57][D][voice_assistant:627]: Event Type: 1
[13:27:57][D][voice_assistant:630]: Assist Pipeline running
[13:27:57][D][voice_assistant:627]: Event Type: 3
[13:27:57][D][voice_assistant:641]: STT started
[13:27:57][D][light:036]: 'top_led' Setting:
[13:27:57][D][light:051]:   Brightness: 100%
[13:27:57][D][light:059]:   Red: 100%, Green: 100%, Blue: 100%
[13:27:57][D][light:109]:   Effect: 'listening'
[13:27:59][I][esp_audio_sources:033][http]: Receive http event: 2
[13:27:59][I][esp_audio_sources:033][http]: Receive http event: 4
[13:27:59][D][esp-idf:000][http]: I (444973) HTTP_STREAM: total_bytes=0

[13:28:00][I][esp_audio_sources:033][http]: Receive http event: 2
[13:28:00][I][esp_audio_sources:033][http]: Receive http event: 4
[13:28:00][D][esp-idf:000][http]: I (445973) HTTP_STREAM: total_bytes=19527

[13:28:00][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:28:00][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=2
[13:28:00][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=192000, duration=0, bytes=-93
[13:28:00][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:28:00][D][esp_audio_processors:108]: Received request from: HTTPStreamReader
[13:28:00][D][esp_audio_processors:113]: New settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:28:00][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:28:00][D][adf_audio_element:108]: Preparing [http]...
[13:28:00][D][adf_audio_element:108]: Preparing [decoder]...
[13:28:00][D][esp-idf:000][decoder]: W (446059) AUDIO_ELEMENT: OUT-[decoder] AEL_IO_ABORT

[13:28:00][D][esp-idf:000][decoder]: W (446063) MP3_DECODER: output aborted -3

[13:28:00][D][esp-idf:000][decoder]: I (446069) MP3_DECODER: Closed

[13:28:00][D][esp_audio_sources:193]: Preparation done!
[13:28:00][D][esp_adf_pipeline:342]: wait for preparation, done
[13:28:00][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:28:00][I][adf_media_player:192]: got new pipeline state: 5, while in MP state PLAYING
[13:28:00][I][adf_media_player:256]: current mp state: PLAYING
[13:28:00][I][adf_media_player:257]: anouncement: false
[13:28:00][I][adf_media_player:258]: play_intent: false
[13:28:00][I][adf_media_player:259]: current_uri_: yes
[13:28:00][D][adf_audio_element:165]: Resuming [http]...
[13:28:00][D][adf_audio_element:172]: [http] Sending resume command.
[13:28:00][D][adf_audio_element:165]: Resuming [decoder]...
[13:28:00][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:28:00][D][esp-idf:000][decoder]: I (446123) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1

[13:28:00][D][esp-idf:000][decoder]: I (446277) MP3_DECODER: MP3 opened

[13:28:00][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:28:00][I][adf_media_player:192]: got new pipeline state: 6, while in MP state PLAYING
[13:28:00][I][adf_media_player:256]: current mp state: PLAYING
[13:28:00][I][adf_media_player:257]: anouncement: false
[13:28:00][I][adf_media_player:258]: play_intent: false
[13:28:00][I][adf_media_player:259]: current_uri_: yes
[13:28:02][I][esp_audio_sources:033][http]: Receive http event: 2
[13:28:02][I][esp_audio_sources:033][http]: Receive http event: 4
[13:28:02][D][esp-idf:000][http]: I (447878) HTTP_STREAM: total_bytes=0

[13:28:03][I][esp_audio_sources:033][http]: Receive http event: 2
[13:28:03][I][esp_audio_sources:033][http]: Receive http event: 4
[13:28:03][D][esp-idf:000][http]: I (448722) HTTP_STREAM: total_bytes=19527

[13:28:03][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:28:03][I][esp_adf_pipeline:132]: [ http ] status: 12
[13:28:03][I][esp_adf_pipeline:132]: [ decoder ] status: 12
[13:28:03][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=2
[13:28:03][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=192000, duration=0, bytes=-93
[13:28:03][D][esp-idf:000][http]: W (449258) HTTP_STREAM: No more data,errno:0, total_bytes:19527, rlen = 0

[13:28:03][I][esp_audio_sources:033][http]: Receive http event: 7
[13:28:03][D][esp-idf:000][http]: I (449269) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0

[13:28:03][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 19527
[13:28:03][I][esp_adf_pipeline:132]: [ http ] status: 15
[13:28:03][I][esp_adf_pipeline:135]: current state: RUNNING
[13:28:03][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[13:28:03][I][adf_media_player:192]: got new pipeline state: 7, while in MP state PLAYING
[13:28:03][I][adf_media_player:256]: current mp state: PLAYING
[13:28:03][I][adf_media_player:257]: anouncement: false
[13:28:03][I][adf_media_player:258]: play_intent: false
[13:28:03][I][adf_media_player:259]: current_uri_: yes
[13:28:04][D][esp-idf:000][decoder]: I (449412) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2

[13:28:04][D][esp-idf:000][decoder]: I (449503) MP3_DECODER: Closed

[13:28:04][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -93
[13:28:04][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[13:28:04][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:04][D][esp-idf:000][resampler]: I (449535) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2

[13:28:04][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[13:28:04][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:04][D][esp-idf:000][i2s_out]: I (449560) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2

[13:28:04][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[13:28:04][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[13:28:04][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:04][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[13:28:04][I][adf_media_player:192]: got new pipeline state: 4, while in MP state PLAYING
[13:28:04][I][adf_media_player:256]: current mp state: IDLE
[13:28:04][I][adf_media_player:257]: anouncement: false
[13:28:04][I][adf_media_player:258]: play_intent: false
[13:28:04][I][adf_media_player:259]: current_uri_: yes
[13:28:05][D][voice_assistant:627]: Event Type: 11
[13:28:05][D][voice_assistant:783]: Starting STT by VAD
[13:28:07][D][voice_assistant:627]: Event Type: 12
[13:28:07][D][voice_assistant:787]: STT by VAD end
[13:28:07][D][voice_assistant:504]: State changed from STREAMING_MICROPHONE to STOP_MICROPHONE
[13:28:07][D][voice_assistant:510]: Desired state set to AWAITING_RESPONSE
[13:28:07][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[13:28:07][D][voice_assistant:504]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[13:28:07][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[13:28:07][D][light:036]: 'top_led' Setting:
[13:28:07][D][light:051]:   Brightness: 70%
[13:28:07][D][light:059]:   Red: 0%, Green: 20%, Blue: 100%
[13:28:07][D][light:109]:   Effect: 'processing'
[13:28:07][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[13:28:07][D][adf_audio_element:324]: [resampler] Checking State for stopping, got 3
[13:28:07][D][esp-idf:000]: E (452995) AUDIO_ELEMENT: [resampler] Element already stopped

[13:28:07][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[13:28:07][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[13:28:07][D][voice_assistant:504]: State changed from STOPPING_MICROPHONE to AWAITING_RESPONSE
[13:28:07][D][voice_assistant:627]: Event Type: 4
[13:28:07][D][voice_assistant:655]: Speech recognised as: "Hallo. Wie geht es dir?"
[13:28:07][D][voice_assistant:627]: Event Type: 5
[13:28:07][D][voice_assistant:660]: Intent started
[13:28:10][D][voice_assistant:627]: Event Type: 6
[13:28:10][D][voice_assistant:627]: Event Type: 7
[13:28:10][D][voice_assistant:683]: Response: "Ach, du weißt schon, mir geht's immer fantastisch. Wie kann ich dir helfen, oder suchst du nur ein bisschen Ablenkung?"
[13:28:10][D][voice_assistant:627]: Event Type: 8
[13:28:10][D][voice_assistant:705]: Response URL: "http://192.168.181.42:8123/api/tts_proxy/3f3a70bd4cb2b0211d668de18d4583066bf9b14e_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3"
[13:28:10][D][voice_assistant:504]: State changed from AWAITING_RESPONSE to STREAMING_RESPONSE
[13:28:10][D][voice_assistant:510]: Desired state set to STREAMING_RESPONSE
[13:28:10][D][media_player:061]: 'Onju 1' - Setting
[13:28:10][D][media_player:068]:   Media URL: http://192.168.181.42:8123/api/tts_proxy/3f3a70bd4cb2b0211d668de18d4583066bf9b14e_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:28:10][D][media_player:074]:  Announcement: yes
[13:28:10][D][adf_media_player:057]: Got control call in state IDLE
[13:28:10][D][adf_media_player:058]: req_track stream uri: http://192.168.181.42:8123/api/tts_proxy/3f3a70bd4cb2b0211d668de18d4583066bf9b14e_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:28:10][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:28:10][D][light:036]: 'top_led' Setting:
[13:28:10][D][light:059]:   Red: 20%, Green: 100%, Blue: 0%
[13:28:10][D][light:109]:   Effect: 'speaking'
[13:28:10][D][voice_assistant:627]: Event Type: 2
[13:28:10][D][voice_assistant:719]: Assist Pipeline ended
[13:28:10][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:28:10][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[13:28:10][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:28:10][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:28:10][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:28:10][I][adf_media_player:257]: anouncement: yes
[13:28:10][I][adf_media_player:258]: play_intent: false
[13:28:10][I][adf_media_player:259]: current_uri_: yes
[13:28:10][D][light:036]: 'top_led' Setting:
[13:28:10][D][light:051]:   Brightness: 60%
[13:28:10][D][light:059]:   Red: 100%, Green: 0%, Blue: 100%
[13:28:10][D][light:109]:   Effect: 'listening_ww'
[13:28:10][D][micro_wake_word:399]: Resetting buffers and probabilities
[13:28:10][D][micro_wake_word:195]: State changed from IDLE to START_MICROPHONE
[13:28:10][D][micro_wake_word:107]: Starting Microphone
[13:28:10][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[13:28:10][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[13:28:10][D][micro_wake_word:195]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[13:28:10][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[13:28:10][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[13:28:10][D][esp_audio_sources:137]: Use fixed settings: no
[13:28:10][D][esp_audio_sources:138]: Streamer status: 6
[13:28:10][D][esp_audio_sources:139]: decoder status: 6
[13:28:10][D][esp_audio_sources:140]: stream uri: http://192.168.181.42:8123/api/tts_proxy/3f3a70bd4cb2b0211d668de18d4583066bf9b14e_de-de_bc69a6d37a_tts.home_assistant_cloud.mp3
[13:28:10][D][adf_audio_element:108]: Preparing [http]...
[13:28:10][D][adf_audio_element:108]: Preparing [decoder]...
[13:28:10][D][adf_audio_element:108]: Preparing [i2s_in]...
[13:28:10][D][adf_audio_element:108]: Preparing [resampler]...
[13:28:10][D][adf_audio_element:108]: Preparing [resampler]...
[13:28:10][D][adf_audio_element:108]: Preparing [i2s_out]...
[13:28:10][D][adf_audio_element:108]: Preparing [pcm_reader]...
[13:28:10][D][esp_audio_sinks:054]: Set bitdepth to 32
[13:28:10][D][esp_audio_sinks:054]: Set bitdepth to 16
[13:28:10][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[13:28:10][D][esp_adf_pipeline:342]: wait for preparation, done
[13:28:10][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:28:10][D][adf_audio_element:165]: Resuming [http]...
[13:28:10][D][adf_audio_element:172]: [http] Sending resume command.
[13:28:10][D][adf_audio_element:165]: Resuming [decoder]...
[13:28:10][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:28:10][D][adf_audio_element:165]: Resuming [i2s_in]...
[13:28:10][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[13:28:10][D][esp-idf:000][i2s_in]: I (456041) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1

[13:28:10][D][adf_audio_element:165]: Resuming [pcm_reader]...
[13:28:10][I][esp_adf_pipeline:132]: [ i2s_in ] status: 12
[13:28:10][D][adf_audio_element:191]: [http] Checking State, got 79
[13:28:10][D][adf_audio_element:191]: [decoder] Checking State, got 79
[13:28:10][D][adf_audio_element:191]: [i2s_in] Checking State, got 78
[13:28:10][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[13:28:10][D][adf_audio_element:191]: [resampler] Checking State, got 78
[13:28:10][D][adf_audio_element:191]: [pcm_reader] Checking State, got 65
[13:28:10][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:28:10][D][micro_wake_word:195]: State changed from STARTING_MICROPHONE to DETECTING_WAKE_WORD
[13:28:11][D][esp-idf:000][http]: I (456584) HTTP_CLIENT: Body received in fetch header state, 0x3fcc7b57, 1841

[13:28:11][D][esp-idf:000][http]: I (456590) HTTP_STREAM: total_bytes=57724

[13:28:11][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:28:11][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=24000, bits=16, ch=1
[13:28:11][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=48000, duration=0, bytes=-1276
[13:28:11][D][adf_i2s_out:141]: Set final i2s settings: 48000
[13:28:11][D][esp_audio_processors:108]: Received request from: HTTPStreamReader
[13:28:11][D][esp_audio_processors:113]: New settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:28:11][D][esp_audio_processors:124]: Current settings: SRC: rate: 24000, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[13:28:11][D][adf_audio_element:108]: Preparing [http]...
[13:28:11][D][adf_audio_element:108]: Preparing [decoder]...
[13:28:11][D][esp-idf:000][decoder]: W (456658) AUDIO_ELEMENT: OUT-[decoder] AEL_IO_ABORT

[13:28:11][D][esp-idf:000][decoder]: W (456663) MP3_DECODER: output aborted -3

[13:28:11][D][esp-idf:000][decoder]: I (456668) MP3_DECODER: Closed

[13:28:11][D][esp_audio_sources:193]: Preparation done!
[13:28:11][D][esp_adf_pipeline:342]: wait for preparation, done
[13:28:11][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[13:28:11][I][adf_media_player:192]: got new pipeline state: 5, while in MP state ANNOUNCING
[13:28:11][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:28:11][I][adf_media_player:257]: anouncement: yes
[13:28:11][I][adf_media_player:258]: play_intent: false
[13:28:11][I][adf_media_player:259]: current_uri_: yes
[13:28:11][D][adf_audio_element:165]: Resuming [http]...
[13:28:11][D][adf_audio_element:172]: [http] Sending resume command.
[13:28:11][D][adf_audio_element:165]: Resuming [decoder]...
[13:28:11][D][adf_audio_element:172]: [decoder] Sending resume command.
[13:28:11][D][esp-idf:000][decoder]: I (456826) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1

[13:28:11][D][esp-idf:000][decoder]: I (456899) MP3_DECODER: MP3 opened

[13:28:11][I][HTTPStreamReader:230]: Codec Format reported: 3.
[13:28:11][I][esp_adf_pipeline:132]: [ http ] status: 12
[13:28:11][D][adf_audio_element:191]: [http] Checking State, got 78
[13:28:11][D][adf_audio_element:191]: [decoder] Checking State, got 78
[13:28:11][I][esp_adf_pipeline:132]: [ decoder ] status: 12
[13:28:11][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=24000, bits=16, ch=1
[13:28:11][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=48000, duration=0, bytes=-1276
[13:28:11][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[13:28:11][I][adf_media_player:192]: got new pipeline state: 6, while in MP state ANNOUNCING
[13:28:11][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:28:11][I][adf_media_player:257]: anouncement: yes
[13:28:11][I][adf_media_player:258]: play_intent: false
[13:28:11][I][adf_media_player:259]: current_uri_: yes
[13:28:19][D][esp-idf:000][http]: W (465127) HTTP_STREAM: No more data,errno:0, total_bytes:57724, rlen = 0

[13:28:19][I][esp_audio_sources:033][http]: Receive http event: 7
[13:28:19][D][esp-idf:000][http]: I (465136) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0

[13:28:19][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 57724
[13:28:19][I][esp_adf_pipeline:132]: [ http ] status: 15
[13:28:19][I][esp_adf_pipeline:135]: current state: RUNNING
[13:28:19][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[13:28:19][I][adf_media_player:192]: got new pipeline state: 7, while in MP state ANNOUNCING
[13:28:19][I][adf_media_player:256]: current mp state: ANNOUNCING
[13:28:19][I][adf_media_player:257]: anouncement: yes
[13:28:19][I][adf_media_player:258]: play_intent: false
[13:28:19][I][adf_media_player:259]: current_uri_: yes
[13:28:20][D][esp-idf:000][decoder]: I (465829) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2

[13:28:20][D][esp-idf:000][decoder]: I (466169) MP3_DECODER: Closed

[13:28:20][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -1276
[13:28:20][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[13:28:20][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:20][D][esp-idf:000][resampler]: I (466275) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2

[13:28:20][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[13:28:20][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:20][D][esp-idf:000][i2s_out]: I (466306) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2

[13:28:21][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[13:28:21][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[13:28:21][I][esp_adf_pipeline:135]: current state: FINISHING
[13:28:21][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[13:28:21][I][adf_media_player:192]: got new pipeline state: 4, while in MP state ANNOUNCING
[13:28:21][I][adf_media_player:256]: current mp state: IDLE
[13:28:21][I][adf_media_player:257]: anouncement: false
[13:28:21][I][adf_media_player:258]: play_intent: false
[13:28:21][I][adf_media_player:259]: current_uri_: yes

tetele / onju-voice-satellite