MicroWakeWord version reboots on every second voice command #98

rmeissn · 2024-11-24T19:46:37Z

rmeissn
Nov 24, 2024

Flavor

MicroWakeWord

Checklist

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

Describe the issue

While I'm not using an Onju Board - sorry for posting here - I've replicated the basic the setup (ESP32-S3 DevKitC-1 N16R8, Max9857 amp, an INMP441 Mic) and copied over a stripped down version of the code from this repository (stripping buttons, timers, led strip), while only adapting some pins to match my wiring for the amp and mic.

The setup works very good, except for asking the voice assistant something the second time. Whenever I'm asking something for the second time, the device seems to reboot on playing the answer. Did any of you encounter something similar?

My problem sounds similar to #24 , but there is no solution in this thread.

I'm using the MicroWakeWord Edition.

Maybe someone can give me an advice or point me onto something for how to proceed...

Reproduction steps

Boot the device
Ask something ("Okay Nabu" -> Question -> Processing -> Answer is played correctly -> RGB Led indicates readiness)
Ask something ("Okay Nabu" -> Question -> Processing -> Board reboot as it receives the answer.)

Debug logs

No response

Code

I've played a bit with this configuration (see comments), but it should largly resemble the onju configuration for the mww version. I've tested both, the version from #77 and the original implementation of the ADF pipelines.

substitutions:
  name: "diy-voice-assistant"
  friendly_name: "DIY Voice Assistant"
  device_description: "Do It Yourself Voice Satellite with ESPHome software and microWakeWord"
  wakeup_sound_url: "http://192.168.0.202:8123/local/wakeup.mp3" # New Notification #7 by UNIVERSFIELD https://freesound.org/people/UNIVERSFIELD/sounds/736267/
  error_sound_url: "http://192.168.0.202:8123/local/error.mp3" # Error #8 by UNIVERSFIELD https://freesound.org/people/UNIVERSFIELD/sounds/734442/
  timer_finished_sound_url: "http://192.168.0.202:8123/local/timer_finished.mp3" # New Notification #6 by UNIVERSFIELD https://freesound.org/people/UNIVERSFIELD/sounds/734445/
  vad_model: "https://raw.githubusercontent.com/esphome/micro-wake-word-models/refs/heads/main/models/v2/vad.json"
  wakeword_model: "https://raw.githubusercontent.com/esphome/micro-wake-word-models/refs/heads/main/models/v2/okay_nabu.json"

external_components:
  - source:
      type: git
      url: https://github.com/gnumpi/esphome_audio
      ref: dev-next
    components: [adf_pipeline, i2s_audio]

esphome:
  name: "${name}"
  friendly_name: "${friendly_name}"
  comment: "${device_description}"
  name_add_mac_suffix: true
  min_version: 2024.9.0
  platformio_options:
    board_build.flash_mode: dio
    #board_build.arduino.memory_type: qio_opi
  on_boot: 
    then:
      - light.turn_on:
          id: rgb_led
          effect: slow_pulse
          red: 100%
          green: 0%
          blue: 0%
      - media_player.volume_set: 70%
      - wait_until:
          condition:
            wifi.connected:
      - light.turn_on:
          id: rgb_led
          effect: pulse
          red: 0%
          green: 100%
          blue: 0%
      - wait_until:
          condition:
            api.connected:
      - light.turn_on:
          id: rgb_led
          effect: none
          red: 0%
          green: 100%
          blue: 0%
      - delay: 5s
      - light.turn_off: rgb_led

esp32:
  board: esp32-s3-devkitc-1
  variant: esp32s3
  flash_size: 16MB  
  framework:
    type: esp-idf
    version: recommended
    sdkconfig_options:
      CONFIG_ESP32_S3_BOX_BOARD: "y"
     # I've found the following options in other repositories, but none of these seem to make a difference
      #CONFIG_ESP32S3_DEFAULT_CPU_FREQ_240: "y"
      #CONFIG_ESP32S3_DATA_CACHE_64KB: "y"
      #CONFIG_ESP32S3_DATA_CACHE_LINE_64B: "y"
      #CONFIG_AUDIO_BOARD_CUSTOM: "y"
      #CONFIG_ESP32_WIFI_STATIC_RX_BUFFER_NUM: "16"
      #CONFIG_ESP32_WIFI_DYNAMIC_RX_BUFFER_NUM: "512"
      #CONFIG_TCPIP_RECVMBOX_SIZE: "512"
      #CONFIG_TCP_SND_BUF_DEFAULT: "65535"
      #CONFIG_TCP_WND_DEFAULT: "512000"
      #CONFIG_TCP_RECVMBOX_SIZE: "512"
  
psram:
  mode: octal
  speed: 80MHz

logger:

ota:
  platform: esphome

wifi:
  ssid: ...
  password: ...
  output_power: 8.5dB
  power_save_mode: none
  fast_connect: true
  manual_ip:
    static_ip: 192.168.0.203
    gateway: 192.168.0.1
    subnet: 255.255.255.0

improv_serial:

api:
  services:
    - service: start_va
      then:
        - voice_assistant.start
    - service: stop_va
      then:
        - voice_assistant.stop

i2s_audio:
  - id: i2s_in
    i2s_lrclk_pin: GPIO8
    i2s_bclk_pin: GPIO9
  - id: i2s_out
    i2s_lrclk_pin: GPIO15
    i2s_bclk_pin: GPIO16
# I've also tested the duplex mode, but it also resulty in reboots

adf_pipeline:
  - platform: i2s_audio
    type: audio_out
    id: adf_i2s_out
    i2s_audio_id: i2s_out
    i2s_dout_pin: GPIO17
    sample_rate: 48000
    alc_max: .5
    use_apll: true
    fixed_settings: true
    channel: left
    #sample_rate: 16000
    #adf_alc: true
    #alc_max: .5
    #bits_per_sample: 32bit
    #fixed_settings: true
    #channel: left
  - platform: i2s_audio
    type: audio_in
    id: adf_i2s_in
    i2s_audio_id: i2s_in
    i2s_din_pin: GPIO10
    channel: left
    pdm: false
    use_apll: true
    sample_rate: 48000
    fixed_settings: true
    #channel: left
    #pdm: false
    #sample_rate: 16000
    #bits_per_sample: 32bit
    #fixed_settings: true
    
microphone:
  - platform: adf_pipeline
    id: mic
    keep_pipeline_alive: true
    gain_log2: 3
    pipeline:
      - adf_i2s_in
      - resampler
      - self

media_player:
  - platform: adf_pipeline
    id: media_out
    name: Media Player
    internal: false
    keep_pipeline_alive: true
    pipeline:
      - self
      - resampler
      - adf_i2s_out

micro_wake_word:
  models:
    - model: "${wakeword_model}"
  vad:
    model: "${vad_model}"
  on_wake_word_detected:
    - if:
        condition: media_player.is_playing
        then:
          - media_player.pause
    - media_player.play_media: "${wakeup_sound_url}"
    - wait_until:
        not:
          media_player.is_playing: media_out
    - voice_assistant.start:
        wake_word: !lambda return wake_word;

voice_assistant:
  id: va
  microphone: mic
  media_player: media_out
  use_wake_word: false
  on_listening:
    - light.turn_on:
        id: rgb_led
        blue: 100%
        red: 100%
        green: 100%
        brightness: 100%
        effect: listening
  on_stt_vad_end:
    - light.turn_on:
        id: rgb_led
        blue: 100%
        red: 0%
        green: 20%
        brightness: 70%
        effect: processing
  on_tts_end:
    - light.turn_on:
        id: rgb_led
        blue: 0%
        red: 20%
        green: 100%
        effect: speaking
  on_end:
    - wait_until:
        media_player.is_idle
    - light.turn_off: rgb_led
    - delay: 100ms
    - if:
        condition:
          - switch.is_on: use_wake_word
        then:
          - micro_wake_word.start
  on_client_connected:
    - if:
        condition:
          - switch.is_on: use_wake_word
        then:
          - micro_wake_word.start:
  on_client_disconnected:
    - if:
        condition:
          - switch.is_on: use_wake_word
        then:
          - voice_assistant.stop:
          - micro_wake_word.stop:
  on_error:
    - media_player.play_media: "${error_sound_url}"
    - light.turn_on:
        id: rgb_led
        blue: 0%
        red: 100%
        green: 0%
        effect: none
    - delay: 1s
    - light.turn_off: rgb_led

# This is the onboard RGB LED
light:
  - platform: esp32_rmt_led_strip
    name: "RGB LED"
    id: rgb_led
    rgb_order: GRB
    pin: GPIO48
    num_leds: 1
    rmt_channel: 0
    chipset: ws2812
    default_transition_length: 100ms
    effects:
      - pulse:
          name: pulse
          transition_length: 250ms
          update_interval: 250ms
      - pulse:
          name: slow_pulse
          transition_length: 1s
          update_interval: 2s
      - addressable_twinkle:
          name: listening_ww
          twinkle_probability: 1%
      - addressable_twinkle:
          name: listening
          twinkle_probability: 45%
      - addressable_scan:
          name: processing
          move_interval: 80ms
      - addressable_flicker:
          name: speaking
          intensity: 35%

script:
  - id: turn_on_wake_word
    then:
      - if:
          condition:
            - switch.is_on: use_wake_word
          then:
            - micro_wake_word.start
            - light.turn_off: rgb_led

  - id: turn_off_wake_word
    then:
      - micro_wake_word.stop
      - light.turn_off: rgb_led

switch:
  - platform: template
    name: Use Wake Word
    id: use_wake_word
    optimistic: true
    restore_mode: RESTORE_DEFAULT_ON
    on_turn_on:
      - script.execute: turn_on_wake_word
    on_turn_off:
      - script.execute: turn_off_wake_word
  - platform: template
    name: Wake Word Listening Light
    id: flicker_wake_word
    entity_category: config
    optimistic: true
    restore_mode: RESTORE_DEFAULT_ON
    on_turn_on:
      - light.turn_off: rgb_led
    on_turn_off:
      - light.turn_off: rgb_led

rmeissn · 2024-11-27T08:30:46Z

rmeissn
Nov 27, 2024
Author

This is one of my serial logs, where the device reboots:

[21:01:27]419) opi psram: Readlatency  : 0x02 (10 cycles@Fixed)

[I][logger:171]: Log initialized
[21:01:27][C][safe_mode:079]: There have been 0 suspected unsuccessful boot attempts
[21:01:27][D][esp32.preferences:114]: Saving 1 preferences to flash...
[21:01:27][D][esp32.preferences:143]: Saving 1 preferences to flash: 0 cached, 1 written, 0 failed
[21:01:27][I][app:029]: Running through setup()...
[21:01:27][C][esp32_rmt_led_strip:021]: Setting up ESP32 LED Strip...
[21:01:27][C][light:021]: Setting up light 'RGB LED'...
[21:01:29][D][light:036]: ESP-ROM:esp32s3-20210327
[21:01:29]Build:Mar 27 2021
[21:01:29]rst:0x15 (USB_UART_CHIP_RESET),boot:0x8 (SPI_FAST_FLASH_BOOT)
[21:01:29]Saved PC:0x420ddfe6
[21:01:29]SPIWP:0xee
[21:01:29]mode:DIO, clock div:1
[21:01:29]load:0x3fce3808,len:0x16c4
[21:01:29]load:0x403c9700,len:0xbc0
[21:01:29]load:0x403cc700,len:0x2e90
[21:01:29]entry 0x403c9950
[21:01:29]I (24) boot: ESP-IDF 4.4.8 2nd stage bootloader
[21:01:29]I (24) boot: compile time 17:59:10
[21:01:29]I (25) boot: Multicore bootloader
[21:01:29]I (26) boot: chip revision: v0.2
[21:01:29]I (30) boot.esp32s3: Boot SPI Speed : 80MHz
[21:01:29]I (35) boot.esp32s3: SPI Mode       : DIO
[21:01:29]I (40) boot.esp32s3: SPI Flash Size : 16MB
[21:01:29]I (45) boot: Enabling RNG early entropy source...
[21:01:29]I (50) boot: Partition Table:
[21:01:29]I (54) boot: ## Label            Usage          Type ST Offset   Length
[21:01:29]I (61) boot:  0 otadata          OTA data         01 00 00009000 00002000
[21:01:29]I (68) boot:  1 phy_init         RF data          01 01 0000b000 00001000
[21:01:29]I (76) boot:  2 app0             OTA app          00 10 00010000 007c0000
[21:01:29]I (83) boot:  3 app1             OTA app          00 11 007d0000 007c0000
[21:01:29]I (91) boot:  4 nvs              WiFi data        01 02 00f90000 0006d000
[21:01:29]I (98) boot: End of partition table
[21:01:29]I (102) esp_image: segment 0: paddr=00010020 vaddr=3c0f0020 size=47630h (292400) map
[21:01:29]I (163) esp_image: segment 1: paddr=00057658 vaddr=3fc98700 size=03f90h ( 16272) load
[21:01:29]I (167) esp_image: segment 2: paddr=0005b5f0 vaddr=40374000 size=04a28h ( 18984) load
[21:01:29]I (173) esp_image: segment 3: paddr=00060020 vaddr=42000020 size=e0274h (918132) map
[21:01:29]I (342) esp_image: segment 4: paddr=0014029c vaddr=40378a28 size=0fcd8h ( 64728) load
[21:01:29]I (365) boot: Loaded app from partition at offset 0x10000
[21:01:29]I (365) boot: Disabling RNG early entropy source...
[21:01:29]I (365) cpu_start: Multicore app
[21:01:29]I (369) opi psram: vendor id : 0x0d (AP)
[21:01:29]I (374) opi psram: dev id    : 0x02 (generation 3)
[21:01:29]I (379) opi psram: density   : 0x03 (64 Mbit)
[21:01:29]I (384) opi psram: good-die  : 0x01 (Pass)
[21:01:29]I (389) opi psram: Latency   : 0x01 (Fixed)
[21:01:29]I (394) opi psram: VCC       : 0x01 (3V)
[21:01:29]I (398) opi psram: SRF       : 0x01 (Fast Refresh)
[21:01:29]I (404) opi psram: BurstType : 0x01 (Hybrid Wrap)
[21:01:29]I (409) opi psram: BurstLen  : 0x01 (32 Byte)
[21:01:29]I (414) opi psram: Readlatency  : 0x02 (10 cycles@Fixed)
[21:01:29]I (420) opi psram: DriveStrength: 0x00 (1/1)
[21:01:29]I (426) MSPI Timing: PSRAM timing tuning index: 5
[21:01:29]I (431) spiram: Found 64MBit SPI RAM device
[21:01:29]I (435) spiram: SPI RAM mode: sram 80m
[21:01:29]I (440) spiram: PSRAM initialized, cache is in normal (1-core) mode.
[21:01:29]I (447) cpu_start: Pro cpu up.
[21:01:29]I (451) cpu_start: Starting app cpu, entry point is 0x40376534
[21:01:29]I (0) cpu_start: App cpu up.
[21:01:30]I (883) spiram: SPI SRAM memory test OK
[21:01:30]I (892) cpu_start: Pro cpu start user code
[21:01:30]I (892) cpu_start: cpu freq: 240000000
[21:01:30]I (892) cpu_start: Application information:
[21:01:30]I (892) cpu_start: Project name:     diy-voice-assistant
[21:01:30]I (893) cpu_start: App version:      2024.11.1
[21:01:30]I (893) cpu_start: Compile time:     Nov 25 2024 13:45:51
[21:01:30]I (893) cpu_start: ELF file SHA256:  3f0140ff85637815...
[21:01:30]I (893) cpu_start: ESP-IDF:          4.4.8
[21:01:30]I (894) cpu_start: Min chip rev:     v0.0
[21:01:30]I (894) cpu_start: Max chip rev:     v0.99 
[21:01:30]I (894) cpu_start: Chip rev:         v0.2
[21:01:30]I (894) heap_init: Initializing. RAM available for dynamic allocation:
[21:01:30]I (894) heap_init: At 3FCA0BE0 len 00048B30 (290 KiB): D/IRAM
[21:01:30]I (895) heap_init: At 3FCE9710 len 00005724 (21 KiB): STACK/DIRAM
[21:01:30]I (895) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
[21:01:30]I (895) heap_init: At 600FE000 len 00002000 (8 KiB): RTCRAM
[21:01:30]I (896) spiram: Adding pool of 8192K of external SPI memory to heap allocator
[21:01:30]I (896) spi_flash: detected chip: boya
[21:01:30]I (897) spi_flash: flash io: dio
[21:01:30]I (898) sleep: Configure to isolate all GPIO pins in sleep state
[21:01:30]I (898) sleep: Enable automatic switching of GPIO sleep configuration
[21:01:30]I (898) cpu_start: Starting scheduler on PRO CPU.
[21:01:30]I (0) cpu_start: Starting scheduler on APP CPU.
[21:01:30][I][logger:171]: Log initialized
[21:01:30][C][safe_mode:079]: There have been 1 suspected unsuccessful boot attempts
[21:01:30][D][esp32.preferences:114]: Saving 1 preferences to flash...
[21:01:30][D][esp32.preferences:143]: Saving 1 preferences to flash: 0 cached, 1 written, 0 failed
[21:01:30][I][app:029]: Running through setup()...
[21:01:30][C][esp32_rmt_led_strip:021]: Setting up ESP32 LED Strip...
[21:01:30][C][light:021]: Setting up light 'RGB LED'...
[21:01:30][D][light:036]: 'RGB LED' Setting:
[21:01:30][D][light:041]:   Color mode: RGB
[21:01:30][D][light:085]:   Transition length: 0.1s
[21:01:30][D][template.switch:046]:   Restored state ON
[21:01:30][D][switch:012]: 'Use Wake Word' Turning ON.
[21:01:30][D][switch:055]: 'Use Wake Word': Sending state ON
[21:01:30][D][template.switch:046]:   Restored state ON
[21:01:30][D][switch:012]: 'Wake Word Listening Light' Turning ON.
[21:01:30][D][switch:055]: 'Wake Word Listening Light': Sending state ON
[21:01:30][D][light:036]: 'RGB LED' Setting:
[21:01:30][D][light:085]:   Transition length: 0.1s
[21:01:30][D][template.switch:046]:   Restored state OFF
[21:01:30][D][switch:016]: 'Pipeline' Turning OFF.
[21:01:30][D][switch:055]: 'Pipeline': Sending state OFF
[21:01:30][D][media_player:065]: 'Media Player' - Setting
[21:01:30][D][media_player:069]:   Command: STOP
[21:01:30][D][esp_adf_pipeline:070]: Called 'stop' while in UNINITIALIZED state.
[21:01:30][D][light:036]: 'RGB LED' Setting:
[21:01:30][D][light:047]:   State: ON
[21:01:30][D][light:059]:   Red: 100%, Green: 0%, Blue: 0%
[21:01:30][D][light:109]:   Effect: 'slow_pulse'
[21:01:30][D][media_player:065]: 'Media Player' - Setting
[21:01:30][D][media_player:075]:   Volume: 0.70
[21:01:30][D][esp_audio_processors:124]: Current settings: SRC: rate: 16000, ch: 2 bits: 16, DST: rate: 16000, ch: 2, bits 16
[21:01:30][C][i2s_audio:024]: Setting up I2S Audio...
[21:01:30][C][i2s_audio:024]: Setting up I2S Audio...
[21:01:30][C][wifi:048]: Setting up WiFi...
[21:01:30][D][esp-idf:000]: I (196) pp: pp rom version: e7ae62f
[21:01:30]
[21:01:30][D][esp-idf:000]: I (197) net80211: net80211 rom version: e7ae62f
[21:01:30]
[21:01:30][D][esp-idf:000]: I (199) wifi:
[21:01:30][D][esp-idf:000]: wifi driver task: 3fca60d0, prio:23, stack:6656, core=0
[21:01:30][D][esp-idf:000]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1301) system_api: Base MAC address is not set
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1302) system_api: read default base MAC address from EFUSE
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1306) wifi:
[21:01:30][D][esp-idf:000][wifi]: wifi firmware version: ff661c3
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1307) wifi:
[21:01:30][D][esp-idf:000][wifi]: wifi certification version: v7.0
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1308) wifi:
[21:01:30][D][esp-idf:000][wifi]: config NVS flash: enabled
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1310) wifi:
[21:01:30][D][esp-idf:000][wifi]: config nano formating: disabled
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1311) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init data frame dynamic rx buffer num: 32
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1313) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init static rx mgmt buffer num: 5
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1314) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init management short buffer num: 32
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1315) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init dynamic tx buffer num: 32
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1317) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init tx cache buffer num: 32
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1318) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init static tx FG buffer num: 2
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1320) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init static rx buffer size: 1600
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1321) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init static rx buffer num: 16
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1323) wifi:
[21:01:30][D][esp-idf:000][wifi]: Init dynamic rx buffer num: 32
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1324) wifi_init: rx ba win: 6
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1325) wifi_init: tcpip mbox: 32
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1326) wifi_init: udp mbox: 6
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1326) wifi_init: tcp mbox: 6
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1327) wifi_init: tcp tx win: 65535
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1328) wifi_init: tcp rx win: 5760
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1328) wifi_init: tcp mss: 1440
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1329) wifi_init: WiFi IRAM OP enabled
[21:01:30]
[21:01:30][D][esp-idf:000]: I (1329) wifi_init: WiFi RX IRAM OP enabled
[21:01:30]
[21:01:30][C][wifi:061]: Starting WiFi...
[21:01:30][C][wifi:062]:   Local MAC: 24:EC:4A:01:C3:B0
[21:01:30][D][esp-idf:000][wifi]: I (1332) phy_init: phy_version 670,b7bc9b9,Apr 30 2024,10:54:13
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1375) wifi:
[21:01:30][D][esp-idf:000][wifi]: mode : sta (24:ec:4a:01:c3:b0)
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1377) wifi:
[21:01:30][D][esp-idf:000][wifi]: enable tsf
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: I (1378) wifi:
[21:01:30][D][esp-idf:000][wifi]: Set ps type: 1
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][D][wifi:760]: Loaded saved fast_connect wifi settings
[21:01:30][I][wifi:313]: WiFi Connecting to 'PYUR CB8FB'...
[21:01:30][D][esp32.preferences:114]: Saving 3 preferences to flash...
[21:01:30][D][esp32.preferences:143]: Saving 3 preferences to flash: 3 cached, 0 written, 0 failed
[21:01:30][D][esp-idf:000][wifi]: I (1390) wifi:
[21:01:30][D][esp-idf:000][wifi]: Set ps type: 1
[21:01:30]
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:30][W][component:157]: Component wifi set Warning flag: associating to network
[21:01:30][D][esp-idf:000][wifi]: I (1393) wifi:
[21:01:30][D][esp-idf:000][wifi]: new:<11,0>, old:<1,0>, ap:<255,255>, sta:<11,0>, prof:1
[21:01:30][D][esp-idf:000][wifi]: 
[21:01:30]
[21:01:31][D][esp-idf:000][wifi]: I (1787) wifi:
[21:01:31][D][esp-idf:000][wifi]: state: init -> auth (b0)
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1792) wifi:
[21:01:31][D][esp-idf:000][wifi]: state: auth -> assoc (0)
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1799) wifi:
[21:01:31][D][esp-idf:000][wifi]: state: assoc -> run (10)
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1963) wifi:
[21:01:31][D][esp-idf:000][wifi]: connected with PYUR CB8FB, aid = 1, channel 11, BW20, bssid = 34:2c:c4:6a:47:44
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1965) wifi:
[21:01:31][D][esp-idf:000][wifi]: security: WPA2-PSK, phy: bgn, rssi: -47
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1967) wifi:
[21:01:31][D][esp-idf:000][wifi]: pm start, type: 1
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1968) wifi:
[21:01:31][D][esp-idf:000][wifi]: set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 0, mt_pti: 25000, mt_time: 10000
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][wifi]: I (1970) wifi:
[21:01:31][D][esp-idf:000][wifi]: AP's beacon interval = 102400 us, DTIM period = 1
[21:01:31][D][esp-idf:000][wifi]: 
[21:01:31]
[21:01:31][D][esp-idf:000][sys_evt] I (1975) esp_netif_handlers: sta ip: 192.168.0.203, mask: 255.255.255.0, gw: 192.168.0.1
[21:01:31]
[21:01:31][C][api:026]: Setting up Home Assistant API server...
[21:01:31][C][micro_wake_word:062]: Setting up microWakeWord...
[21:01:31][C][micro_wake_word:069]: Micro Wake Word initialized
[21:01:31][I][app:062]: setup() finished successfully!
[21:01:31][W][component:170]: Component wifi cleared Warning flag
[21:01:31][W][component:157]: Component api set Warning flag: unspecified
[21:01:31][I][app:100]: ESPHome version 2024.11.1 compiled on Nov 26 2024, 19:57:47
[21:01:31][C][wifi:600]: WiFi:
[21:01:31][C][wifi:428]:   Local MAC: 24:EC:4A:01:C3:B0
[21:01:31][C][wifi:433]:   SSID: 'PYUR CB8FB'[redacted]
[21:01:31][C][wifi:436]:   IP Address: 192.168.0.203
[21:01:31][C][wifi:440]:   BSSID: 34:2C:C4:6A:47:44[redacted]
[21:01:31][C][wifi:441]:   Hostname: 'diy-voice-assistant-01c3b0'
[21:01:31][C][wifi:443]:   Signal strength: -46 dB ▂▄▆█
[21:01:31][C][wifi:447]:   Channel: 11
[21:01:31][C][wifi:448]:   Subnet: 255.255.255.0
[21:01:31][C][wifi:449]:   Gateway: 192.168.0.1
[21:01:31][C][wifi:450]:   DNS1: 0.0.0.0
[21:01:31][C][wifi:451]:   DNS2: 0.0.0.0
[21:01:31][C][wifi:453]:   BTM: disabled
[21:01:31][C][wifi:454]:   RRM: enabled
[21:01:31][C][logger:185]: Logger:
[21:01:31][C][logger:186]:   Level: DEBUG
[21:01:31][C][logger:188]:   Log Baud Rate: 115200
[21:01:31][C][logger:189]:   Hardware UART: USB_SERIAL_JTAG
[21:01:31][C][esp32_rmt_led_strip:187]: ESP32 RMT LED Strip:
[21:01:31][C][esp32_rmt_led_strip:188]:   Pin: 48
[21:01:31][C][esp32_rmt_led_strip:189]:   Channel: 0
[21:01:31][C][esp32_rmt_led_strip:214]:   RGB Order: GRB
[21:01:31][C][esp32_rmt_led_strip:215]:   Max refresh rate: 0
[21:01:31][C][esp32_rmt_led_strip:216]:   Number of LEDs: 1
[21:01:31][C][light:092]: Light 'RGB LED'
[21:01:31][C][light:094]:   Default Transition Length: 0.1s
[21:01:31][C][light:095]:   Gamma Correct: 2.80
[21:01:31][C][template.switch:068]: Template Switch 'Use Wake Word'
[21:01:31][C][template.switch:091]:   Restore Mode: restore defaults to ON
[21:01:31][C][template.switch:057]:   Optimistic: YES
[21:01:31][C][template.switch:068]: Template Switch 'Wake Word Listening Light'
[21:01:31][C][template.switch:091]:   Restore Mode: restore defaults to ON
[21:01:31][C][template.switch:057]:   Optimistic: YES
[21:01:31][C][template.switch:068]: Template Switch 'Pipeline'
[21:01:31][C][template.switch:091]:   Restore Mode: restore defaults to OFF
[21:01:31][C][template.switch:057]:   Optimistic: YES
[21:01:31][C][psram:020]: PSRAM:
[21:01:31][C][psram:021]:   Available: YES
[21:01:31][C][psram:024]:   Size: 8191 KB
[21:01:31][C][i2s_audio:028]: I2SController:
[21:01:31][C][i2s_audio:029]:   AccessMode: exclusive
[21:01:31][C][i2s_audio:030]:   Port: 0
[21:01:31][C][i2s_audio:032]:   Reader registered.
[21:01:31][C][i2s_audio:028]: I2SController:
[21:01:31][C][i2s_audio:029]:   AccessMode: exclusive
[21:01:31][C][i2s_audio:030]:   Port: 1
[21:01:31][C][i2s_audio:035]:   Writer registered.
[21:01:31][C][i2s_audio:139]: I2S-Writer (Fixed-CFG):
[21:01:31][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[21:01:31][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[21:01:31][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[21:01:31][C][i2s_audio:136]: I2S-Reader (Fixed-CFG):
[21:01:31][C][i2s_audio:141]:   sample-rate: 48000 bits_per_sample: 32
[21:01:31][C][i2s_audio:142]:   channel_fmt: 4 channels: 1
[21:01:31][C][i2s_audio:143]:   use_apll: yes, use_pdm: no
[21:01:31][C][restart.button:017]: Restart Button 'DIY Voice Assistant REBOOT'
[21:01:31][C][restart.button:017]:   Icon: 'mdi:restart'
[21:01:31][C][mdns:116]: mDNS:
[21:01:31][C][mdns:117]:   Hostname: diy-voice-assistant-01c3b0
[21:01:31][C][esphome.ota:073]: Over-The-Air updates:
[21:01:31][C][esphome.ota:074]:   Address: 192.168.0.203:3232
[21:01:31][C][esphome.ota:075]:   Version: 2
[21:01:31][C][safe_mode:018]: Safe Mode:
[21:01:31][C][safe_mode:020]:   Boot considered successful after 60 seconds
[21:01:31][C][safe_mode:021]:   Invoke after 10 boot attempts
[21:01:31][C][safe_mode:023]:   Remain in safe mode for 300 seconds
[21:01:31][C][api:140]: API Server:
[21:01:31][C][api:141]:   Address: 192.168.0.203:6053
[21:01:31][C][api:145]:   Using noise encryption: NO
[21:01:31][C][micro_wake_word:051]: microWakeWord:
[21:01:31][C][micro_wake_word:052]:   models:
[21:01:31][C][micro_wake_word:015]:     - Wake Word: Okay Nabu
[21:01:31][C][micro_wake_word:016]:       Probability cutoff: 0.970
[21:01:31][C][micro_wake_word:017]:       Sliding window size: 5
[21:01:31][C][micro_wake_word:021]:     - VAD Model
[21:01:31][C][micro_wake_word:022]:       Probability cutoff: 0.500
[21:01:31][C][micro_wake_word:023]:       Sliding window size: 5
[21:01:31][D][ring_buffer:034]: Created ring buffer with size 2048
[21:01:31][D][micro_wake_word:399]: Resetting buffers and probabilities
[21:01:31][D][micro_wake_word:195]: State changed from IDLE to START_MICROPHONE
[21:01:31][D][light:036]: 'RGB LED' Setting:
[21:01:31][D][light:047]:   State: OFF
[21:01:31][D][light:085]:   Transition length: 0.1s
[21:01:31][D][light:091]:   Effect: 'None'
[21:01:31][D][micro_wake_word:107]: Starting Microphone
[21:01:31][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[21:01:31][D][esp_adf_pipeline:060]: Starting request, current state UNINITIALIZED
[21:01:31][D][micro_wake_word:195]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[21:01:31][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from UNINITIALIZED to INITIALIZING. (REQ: 0)
[21:01:31][D][i2s_audio:067]: Install driver requested by Reader
[21:01:31][D][esp-idf:000]: I (2321) I2S: DMA Malloc info, datalen=blocksize=1024, dma_buf_count=4
[21:01:31]
[21:01:31][D][i2s_audio:073]: Installing driver : yes
[21:01:31][D][esp_adf_pipeline:497]: pipeline tag 0, i2s_in
[21:01:31][D][esp_adf_pipeline:497]: pipeline tag 1, resampler
[21:01:31][D][esp_adf_pipeline:497]: pipeline tag 2, pcm_reader
[21:01:31][D][esp-idf:000]: I (2325) AUDIO_PIPELINE: link el->rb, el:0x3d80ed84, tag:i2s_in, rb:0x3d80f1e8
[21:01:31]
[21:01:31][D][esp-idf:000]: I (2326) AUDIO_PIPELINE: link el->rb, el:0x3d80ef5c, tag:resampler, rb:0x3d80fa28
[21:01:31]
[21:01:31][D][esp_adf_pipeline:507]: Setting up event listener.
[21:01:31][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from INITIALIZING to CREATED. (REQ: 0)
[21:01:31][C][esp_adf_pipeline.microphone:020]: ADF-Microphone
[21:01:31][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from CREATED to PREPARING. (REQ: 0)
[21:01:31][C][adf_media_player:016]: ESP-ADF-MediaPlayer:
[21:01:31][C][adf_media_player:018]:   MP_ANNOUNCE enabled
[21:01:31][C][adf_media_player:024]:   Number of ADFComponents: 3
[21:01:31][D][adf_audio_element:108]: Preparing [i2s_in]...
[21:01:31][D][esp-idf:000]: I (2334) AUDIO_ELEMENT: [i2s_in-0x3d80ed84] Element task created
[21:01:31]
[21:01:31][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:31][D][esp-idf:000]: I (2336) AUDIO_THREAD: The resampler task allocate stack on external memory
[21:01:31]
[21:01:31][D][esp-idf:000]: I (2337) AUDIO_ELEMENT: [resampler-0x3d80ef5c] Element task created
[21:01:31]
[21:01:31][D][adf_audio_element:108]: Preparing [pcm_reader]...
[21:01:32][D][esp-idf:000]: I (2339) AUDIO_ELEMENT: [pcm_reader-0x3d80f0a0] Element task created
[21:01:32]
[21:01:32][D][esp_audio_sinks:054]: Set bitdepth to 32
[21:01:32][D][esp_audio_sinks:054]: Set bitdepth to 16
[21:01:32][D][esp_audio_processors:108]: Received request from: Resampler
[21:01:32][D][esp_audio_processors:113]: New settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:01:32][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:01:32][D][esp_adf_pipeline:342]: wait for preparation, done
[21:01:32][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:01:32][D][adf_audio_element:165]: Resuming [i2s_in]...
[21:01:37][D][adf_audio_element:172]: [i2s_in] Sending r[D][micro_wake_word:162]: The 'Okay Nabu' model sliding average probability is 0.984 and most recent probability is 1.000
[21:01:37][D][micro_wake_word:123]: Wake Word 'Okay Nabu' Detected
[21:01:37][D][micro_wake_word:195]: State changed from DETECTING_WAKE_WORD to STOP_MICROPHONE
[21:01:37][D][micro_wake_word:129]: Stopping Microphone
[21:01:37][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[21:01:37][D][micro_wake_word:195]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[21:01:37][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[21:01:37][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[21:01:37][D][esp-idf:000][resampler]: W (8325) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT
[21:01:37]
[21:01:37][D][esp-idf:000][i2s_in]: W (8328) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:37]
[21:01:37][D][esp-idf:000][i2s_in]: W (8329) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:37]
[21:01:37][D][esp-idf:000][i2s_in]: W (8329) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:37]
[21:01:37][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[21:01:37][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[21:01:37][D][micro_wake_word:195]: State changed from STOPPING_MICROPHONE to IDLE
[21:01:37][D][media_player:065]: 'Media Player' - Setting
[21:01:37][D][media_player:072]:   Media URL: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:37][D][esp_audio_sources:098]: Set new uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:37][D][adf_media_player:057]: Got control call in state IDLE
[21:01:37][D][adf_media_player:058]: req_track stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:37][D][esp_adf_pipeline:060]: Starting request, current state UNINITIALIZED
[21:01:37][E][voice_assistant:575]: No API client connected
[21:01:37][D][voice_assistant:512]: State changed from IDLE to IDLE
[21:01:37][D][voice_assistant:518]: Desired state set to IDLE
[21:01:37][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from UNINITIALIZED to INITIALIZING. (REQ: 0)
[21:01:37][I][adf_media_player:192]: got new pipeline state: 1, while in MP state IDLE
[21:01:37][I][adf_media_player:256]: current mp state: IDLE
[21:01:37][I][adf_media_player:257]: anouncement: false
[21:01:37][I][adf_media_player:258]: play_intent: false
[21:01:37][I][adf_media_player:259]: current_uri_: yes
[21:01:37][D][esp-idf:000]: I (8385) MP3_DECODER: MP3 init
[21:01:37]
[21:01:37][D][i2s_audio:067]: Install driver requested by Writer
[21:01:37][D][esp-idf:000]: I (8387) I2S: DMA Malloc info, datalen=blocksize=1024, dma_buf_count=4
[21:01:37]
[21:01:37][D][i2s_audio:073]: Installing driver : yes
[21:01:37][D][esp_adf_pipeline:497]: pipeline tag 0, http
[21:01:37][D][esp_adf_pipeline:497]: pipeline tag 1, decoder
[21:01:37][D][esp_adf_pipeline:497]: pipeline tag 2, resampler
[21:01:37][D][esp_adf_pipeline:497]: pipeline tag 3, i2s_out
[21:01:37][D][esp-idf:000]: I (8391) AUDIO_PIPELINE: link el->rb, el:0x3d800afc, tag:http, rb:0x3d801178
[21:01:37]
[21:01:37][D][esp-idf:000]: I (8392) AUDIO_PIPELINE: link el->rb, el:0x3d800ca8, tag:decoder, rb:0x3d8021b8
[21:01:37]
[21:01:37][D][esp-idf:000]: I (8393) AUDIO_PIPELINE: link el->rb, el:0x3d800e44, tag:resampler, rb:0x3d8031f8
[21:01:37]
[21:01:37][D][esp_adf_pipeline:507]: Setting up event listener.
[21:01:37][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from INITIALIZING to CREATED. (REQ: 0)
[21:01:37][I][adf_media_player:192]: got new pipeline state: 2, while in MP state IDLE
[21:01:37][I][adf_media_player:256]: current mp state: IDLE
[21:01:37][I][adf_media_player:257]: anouncement: false
[21:01:37][I][adf_media_player:258]: play_intent: false
[21:01:37][I][adf_media_player:259]: current_uri_: yes
[21:01:38][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from CREATED to PREPARING. (REQ: 0)
[21:01:38][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[21:01:38][D][adf_i2s_out:141]: Set final i2s settings: 48000
[21:01:38][D][esp_audio_processors:124]: Current settings: SRC: rate: 16000, ch: 2 bits: 16, DST: rate: 16000, ch: 2, bits 16
[21:01:38][I][adf_media_player:256]: current mp state: PLAYING
[21:01:38][I][adf_media_player:257]: anouncement: false
[21:01:38][I][adf_media_player:258]: play_intent: false
[21:01:38][I][adf_media_player:259]: current_uri_: yes
[21:01:38][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[21:01:38][D][esp_audio_sources:137]: Use fixed settings: no
[21:01:38][D][esp_audio_sources:138]: Streamer status: 1
[21:01:38][D][esp_audio_sources:139]: decoder status: 1
[21:01:38][D][esp_audio_sources:140]: stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:38][D][adf_audio_element:108]: Preparing [http]...
[21:01:38][D][esp-idf:000]: I (8418) AUDIO_THREAD: The http task allocate stack on external memory
[21:01:38]
[21:01:38][D][esp-idf:000]: I (8419) AUDIO_ELEMENT: [http-0x3d800afc] Element task created
[21:01:38]
[21:01:38][D][adf_audio_element:108]: Preparing [decoder]...
[21:01:38][D][esp-idf:000]: I (8420) AUDIO_THREAD: The decoder task allocate stack on external memory
[21:01:38]
[21:01:38][D][esp-idf:000]: I (8422) AUDIO_ELEMENT: [decoder-0x3d800ca8] Element task created
[21:01:38]
[21:01:38][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:38][D][esp-idf:000]: I (8423) AUDIO_THREAD: The resampler task allocate stack on external memory
[21:01:38]
[21:01:38][D][esp-idf:000]: I (8424) AUDIO_ELEMENT: [resampler-0x3d800e44] Element task created
[21:01:38]
[21:01:38][D][adf_audio_element:108]: Preparing [i2s_out]...
[21:01:38][D][esp-idf:000]: I (8439) AUDIO_ELEMENT: [i2s_out-0x3d801000] Element task created
[21:01:38]
[21:01:38][D][adf_audio_element:165]: Resuming [http]...
[21:01:38][D][adf_audio_element:172]: [http] Sending resume command.
[21:01:38][D][adf_audio_element:165]: Resuming [decoder]...
[21:01:38][D][adf_audio_element:172]: [decoder] Sending resume command.
[21:01:38][D][esp-idf:000][decoder]: I (8477) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1
[21:01:38]
[21:01:38][D][esp-idf:000][decoder]: I (8479) MP3_DECODER: MP3 opened
[21:01:38]
[21:01:38][I][esp_audio_sources:033][http]: Receive http event: 2
[21:01:38][I][esp_audio_sources:033][http]: Receive http event: 4
[21:01:38][D][adf_audio_ele[D][esp-idf:000][http]: W (8990) HTTP_STREAM: No more data,errno:0, total_bytes:10044, rlen = 0
[21:01:38]
[21:01:38][I][esp_audio_sources:033][http]: Receive http event: 7
[21:01:38][D][esp-idf:000][http]: I (8992) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0
[21:01:38]
[21:01:38][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 10044
[21:01:38][I][esp_adf_pipeline:132]: [ http ] status: 15
[21:01:38][I][esp_adf_pipeline:135]: current state: RUNNING
[21:01:38][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[21:01:38][I][adf_media_player:192]: got new pipeline state: 7, while in MP state PLAYING
[21:01:38][I][adf_media_player:256]: current mp state: PLAYING
[21:01:38][I][adf_media_player:257]: anouncement: false
[21:01:38][I][adf_media_player:258]: play_intent: false
[21:01:38][I][adf_media_player:259]: current_uri_: yes
[21:01:38][D][esp-idf:000][decoder]: I (9278) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2
[21:01:38]
[21:01:38][D][esp-idf:000][decoder]: I (9562) MP3_DECODER: Closed
[21:01:38]
[21:01:38][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -115
[21:01:38][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[21:01:38][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:38][D][esp-idf:000][resampler]: I (9594) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2
[21:01:38]
[21:01:38][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[21:01:38][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:38][D][esp-idf:000][i2s_out]: I (9621) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2
[21:01:38]
[21:01:38][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[21:01:38][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[21:01:38][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:38][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[21:01:38][I][adf_media_player:192]: got new pipeline state: 4, while in MP state PLAYING
[21:01:38][I][adf_media_player:256]: current mp state: IDLE
[21:01:38][I][adf_media_player:257]: anouncement: false
[21:01:38][I][adf_media_player:258]: play_intent: false
[21:01:39][I][adf_media_player:259]: current_uri_: yes
[21:01:41][D][api:103]: Accepted 192.168.0.202
[21:01:41][W][component:170]: Component api cleared Warning flag
[21:01:41][D][light:036]: 'RGB LED' Setting:
[21:01:41][D][light:047]:   State: ON
[21:01:41][D][light:059]:   Red: 0%, Green: 100%, Blue: 0%
[21:01:41][D][light:085]:   Transition length: 0.1s
[21:01:41][D][api.connection:1446]: Home Assistant 2024.11.3 (192.168.0.202): Connected successfully
[21:01:41][D][micro_wake_word:399]: Resetting buffers and probabilities
[21:01:41][D][micro_wake_word:195]: State changed from IDLE to START_MICROPHONE
[21:01:41][D][micro_wake_word:107]: Starting Microphone
[21:01:41][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[21:01:41][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:01:41][D][micro_wake_word:195]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[21:01:41][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:01:41][D][adf_audio_element:108]: Preparing [i2s_in]...
[21:01:41][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:41][D][adf_audio_element:108]: Preparing [pcm_reader]...
[21:01:41][D][esp_audio_sinks:054]: Set bitdepth to 32
[21:01:41][D][esp_audio_sinks:054]: Set bitdepth to 16
[21:01:41][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:01:41][D][esp_adf_pipeline:342]: wait for preparation, done
[21:01:41][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:01:41][D][adf_audio_element:165]: Resuming [i2s_in]...
[21:01:41][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[21:01:41][D][esp-idf:000][i2s_in]: I (11934) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1
[21:01:41]
[21:01:41][D][adf_audio_element:165]: Resuming [resampler]...
[21:01:41][D][adf_audio_element:172]: [resampler] Sending resume command.
[21:01:41][I][esp_adf_pipeline:132]: [ i2s_in ] status: 12
[21:01:41][D][adf_audio_element:165]: Resuming [pcm_reader]...
[21:01:41][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[21:01:41][D][adf_audio_element:191]: [i2s_in] Checking State, got 78
[21:01:41][D][adf_audio_element:191]: [resampler] Checking State, got 78
[21:01:41][D][adf_audio_element:191]: [pcm_reader] Checking State, got 65
[21:01:41][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[21:01:41][D][micro_wake_word:195]: State changed from STARTING_MICROPHONE to DETECTING_WAKE_WORD
[21:01:46][D][light:036]: 'RGB LED' Setting:
[21:01:46][D][light:047]:   State: OFF
[21:01:46][D][light:085]:   Transition length: 0.1s
[21:01:52][D][micro_wake_word:162]: The 'Okay Nabu' model sliding average probability is 0.998 and most recent probability is 1.000
[21:01:52][D][micro_wake_word:123]: Wake Word 'Okay Nabu' Detected
[21:01:52][D][micro_wake_word:195]: State changed from DETECTING_WAKE_WORD to STOP_MICROPHONE
[21:01:52][D][micro_wake_word:129]: Stopping Microphone
[21:01:52][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[21:01:52][D][micro_wake_word:195]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[21:01:52][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[21:01:52][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[21:01:52][D][esp-idf:000][resampler]: W (23077) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT
[21:01:52]
[21:01:52][D][esp-idf:000][i2s_in]: W (23079) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:52]
[21:01:52][D][esp-idf:000][i2s_in]: W (23080) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:52]
[21:01:52][D][esp-idf:000][i2s_in]: W (23081) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:52]
[21:01:52][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[21:01:52][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[21:01:52][D][micro_wake_word:195]: State changed from STOPPING_MICROPHONE to IDLE
[21:01:52][D][media_player:065]: 'Media Player' - Setting
[21:01:52][D][media_player:072]:   Media URL: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:52][D][esp_audio_sources:098]: Set new uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:52][D][adf_media_player:057]: Got control call in state IDLE
[21:01:52][D][adf_media_player:058]: req_track stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:52][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:01:52][D][voice_assistant:512]: State changed from IDLE to START_MICROPHONE
[21:01:52][D][voice_assistant:518]: Desired state set to START_PIPELINE
[21:01:52][D][voice_assistant:222]: Starting Microphone
[21:01:52][D][ring_buffer:034]: Created ring buffer with size 16384
[21:01:52][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[21:01:52][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:01:52][D][voice_assistant:512]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[21:01:52][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:01:52][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:01:52][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[21:01:52][D][adf_i2s_out:141]: Set final i2s settings: 48000
[21:01:52][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[21:01:52][I][adf_media_player:256]: current mp state: PLAYING
[21:01:52][I][adf_media_player:257]: anouncement: false
[21:01:52][I][adf_media_player:258]: play_intent: false
[21:01:52][I][adf_media_player:259]: current_uri_: yes
[21:01:52][D][adf_audio_element:108]: Preparing [i2s_in]...
[21:01:52][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[21:01:52][D][esp_audio_sources:137]: Use fixed settings: no
[21:01:52][D][esp_audio_sources:138]: Streamer status: 6
[21:01:52][D][esp_audio_sources:139]: decoder status: 6
[21:01:52][D][esp_audio_sources:140]: stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:01:52][D][adf_audio_element:108]: Preparing [http]...
[21:01:52][D][adf_audio_element:108]: Preparing [decoder]...
[21:01:52][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:52][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:52][D][adf_audio_element:108]: Preparing [pcm_reader]...
[21:01:52][D][adf_audio_element:108]: Preparing [i2s_out]...
[21:01:52][D][esp_audio_sinks:054]: Set bitdepth to 32
[21:01:52][D][esp_audio_sinks:054]: Set bitdepth to 16
[21:01:52][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:01:52][D][esp_adf_pipeline:342]: wait for preparation, done
[21:01:53][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:01:53][D][adf_audio_element:165]: Resuming [i2s_in]...
[21:01:53][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[21:01:53][D][adf_audio_element:165]: Resuming [http]...
[21:01:53][D][adf_audio_element:172]: [http] Sending resume command.
[21:01:53][D][adf_audio_element:165]: Resuming [decoder]...
[21:01:53][D][adf_audio_element:172]: [decoder] Sending resume command.
[21:01:53][D][adf_audio_element:165]: Resuming [resampler]...
[21:01:53][D][adf_audio_element:172]: [resampler] Sending resume command.
[21:01:53][D][esp-idf:000][resampler]: I (23163) AUDIO_ELEMENT: [resampler] AEL_MSG_CMD_RESUME,state:1
[21:01:53]
[21:01:53][D][adf_audio_element:191]: [resampler] Checking State, got 79
[21:01:53][D][esp-idf:000][resampler]: I (23169) RSP_FILTER: sample rate of source data : 48000, channel of source data : 1, sample rate of destination data : 16000, channel of destination data : 1
[21:01:53]
[21:01:53][D][voice_assistant:512]: State changed from START_PIPELINE to STARTING_PIPELINE
[21:01:53][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[21:01:53][I][esp_audio_sources:033][http]: Receive http event: 2
[21:01:53][I][esp_audio_sources:033][http]: Receive http event: 4
[21:01:53][D][voice_assistant:635]: Event Type: 1
[21:01:53][D][voice_assistant:638]: Assist Pipeline running
[21:01:53][D][voice_assistant:635]: Event Type: 3
[21:01:53][D][voice_assistant:649]: STT started
[21:01:53][I][HTTPStreamReader:230]: Codec Format reported: 3.
[21:01:53][D][main:817]: Listening for commands...
[21:01:53][D][light:036]: 'RGB LED' Setting:
[21:01:53][D][light:047]:   State: ON
[21:01:53][D][light:051]:   Brightness: 100%
[21:01:53][D][light:059]:   Red: 100%, Green: 100%, Blue: 100%
[21:01:53][D][light:109]:   Effect: 'listening'
[21:01:53][W][micro_wake_word:183]: Wake word is already stopped
[21:01:53][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=2
[21:01:53][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=101000, duration=783, bytes=-115
[21:01:53][D][adf_i2s_out:141]: Set final i2s [D][esp-idf:000][decoder]: I (23943) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2
[21:01:53]
[21:01:53][D][esp-idf:000][decoder]: I (24223) MP3_DECODER: Closed
[21:01:53]
[21:01:53][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -115
[21:01:53][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[21:01:53][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:53][D][esp-idf:000][resampler]: I (24255) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2
[21:01:53]
[21:01:53][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[21:01:53][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:53][D][esp-idf:000][i2s_out]: I (24276) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2
[21:01:53]
[21:01:53][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[21:01:53][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[21:01:53][I][esp_adf_pipeline:135]: current state: FINISHING
[21:01:53][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[21:01:53][I][adf_media_player:192]: got new pipeline state: 4, while in MP state PLAYING
[21:01:53][I][adf_media_player:256]: current mp state: IDLE
[21:01:53][I][adf_media_player:257]: anouncement: false
[21:01:53][I][adf_media_player:258]: play_intent: false
[21:01:53][I][adf_media_player:259]: current_uri_: yes
[21:01:54][D][voice_assistant:635]: Event Type: 11
[21:01:54][D][voice_assistant:795]: Starting STT by VAD
[21:01:55][D][voice_assistant:635]: Event Type: 12
[21:01:55][D][voice_assistant:799]: STT by VAD end
[21:01:55][D][voice_assistant:512]: State changed from STREAMING_MICROPHONE to STOP_MICROPHONE
[21:01:55][D][voice_assistant:518]: Desired state set to AWAITING_RESPONSE
[21:01:55][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[21:01:55][D][voice_assistant:512]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[21:01:55][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[21:01:55][D][main:328]: VAD end ...
[21:01:55][D][light:036]: 'RGB LED' Setting:
[21:01:55][D][light:051]:   Brightness: 70%
[21:01:55][D][light:059]:   Red: 0%, Green: 20%, Blue: 100%
[21:01:55][D][light:109]:   Effect: 'processing'
[21:01:55][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[21:01:55][D][esp-idf:000][resampler]: W (25885) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT
[21:01:55]
[21:01:55][D][esp-idf:000][i2s_in]: W (25890) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:55]
[21:01:55][D][esp-idf:000][i2s_in]: W (25891) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:55]
[21:01:55][D][esp-idf:000][i2s_in]: W (25892) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:01:55]
[21:01:55][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[21:01:55][D][voice_assistant:512]: State changed from STOPPING_MICROPHONE to AWAITING_RESPONSE
[21:01:56][D][voice_assistant:635]: Event Type: 4
[21:01:56][D][voice_assistant:663]: Speech recognised as: "Welcher Tag ist heute?"
[21:01:56][D][voice_assistant:635]: Event Type: 5
[21:01:56][D][voice_assistant:668]: Intent started
[21:01:57][D][voice_assistant:635]: Event Type: 6
[21:01:57][D][voice_assistant:635]: Event Type: 7
[21:01:57][D][voice_assistant:691]: Response: "Heute ist Dienstag, der 26. November 2024."
[21:01:57][D][main:242]: Starting TTS...
[21:01:57][D][voice_assistant:635]: Event Type: 8
[21:01:57][D][voice_assistant:713]: Response URL: "http://192.168.0.202:8123/api/tts_proxy/65386b663588748caefda70a29987dcd92fd3b66_de_bf1225bb9d_tts.elevenlabs.mp3"
[21:01:57][D][voice_assistant:512]: State changed from AWAITING_RESPONSE to STREAMING_RESPONSE
[21:01:57][D][voice_assistant:518]: Desired state set to STREAMING_RESPONSE
[21:01:57][D][media_player:065]: 'Media Player' - Setting
[21:01:57][D][media_player:072]:   Media URL: http://192.168.0.202:8123/api/tts_proxy/65386b663588748caefda70a29987dcd92fd3b66_de_bf1225bb9d_tts.elevenlabs.mp3
[21:01:57][D][media_player:078]:  Announcement: yes
[21:01:57][D][adf_media_player:057]: Got control call in state IDLE
[21:01:58][D][adf_media_player:058]: req_track stream uri: http://192.168.0.202:8123/api/tts_proxy/65386b663588748caefda70a29987dcd92fd3b66_de_bf1225bb9d_tts.elevenlabs.mp3
[21:01:58][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:01:58][D][main:247]: TTS end ...
[21:01:58][D][light:036]: 'RGB LED' Setting:
[21:01:58][D][light:059]:   Red: 20%, Green: 100%, Blue: 0%
[21:01:58][D][light:109]:   Effect: 'speaking'
[21:01:58][D][voice_assistant:635]: Event Type: 2
[21:01:58][D][voice_assistant:727]: Assist Pipeline ended
[21:01:58][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:01:58][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[21:01:58][D][adf_i2s_out:141]: Set final i2s settings: 48000
[21:01:58][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 2 bits: 16, DST: rate: 48000, ch: 1, bits 16
[21:01:58][I][adf_media_player:256]: current mp state: ANNOUNCING
[21:01:58][I][adf_media_player:257]: anouncement: yes
[21:01:58][I][adf_media_player:258]: play_intent: false
[21:01:58][I][adf_media_player:259]: current_uri_: yes
[21:01:58][D][main:258]: Voice assistant session ended.
[21:01:58][D][light:036]: 'RGB LED' Setting:
[21:01:58][D][light:047]:   State: OFF
[21:01:58][D][light:085]:   Transition length: 0.1s
[21:01:58][D][light:091]:   Effect: 'None'
[21:01:58][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[21:01:58][D][esp_audio_sources:137]: Use fixed settings: no
[21:01:58][D][esp_audio_sources:138]: Streamer status: 6
[21:01:58][D][esp_audio_sources:139]: decoder status: 6
[21:01:58][D][esp_audio_sources:140]: stream uri: http://192.168.0.202:8123/api/tts_proxy/65386b663588748caefda70a29987dcd92fd3b66_de_bf1225bb9d_tts.elevenlabs.mp3
[21:01:58][D][adf_audio_element:108]: Preparing [http]...
[21:01:58][D][adf_audio_element:108]: Preparing [decoder]...
[21:01:58][D][adf_audio_element:108]: Preparing [resampler]...
[21:01:58][D][adf_audio_element:108]: Preparing [i2s_out]...
[21:01:58][D][adf_audio_element:165]: Resuming [http]...
[21:01:58][D][adf_audio_element:172]: [http] Sending resume command.
[21:01:58][D][adf_audio_element:165]: Resuming [decoder]...
[21:01:58][D][adf_audio_element:172]: [decoder] Sending resume command.
[21:01:58][D][esp-idf:000][decoder]: I (28645) AUDIO_ELEMENT: [decoder] AEL_MSG_CMD_RESUME,state:1
[21:01:58]
[21:01:58][D][esp-idf:000][decoder]: I (28654) MP3_DECODER: MP3 opened
[21:01:58]
[21:01:58][D][esp-idf:000][http]: I (28658) HTTP_STREAM: total_bytes=52183
[21:01:58]
[21:01:58][I][HTTPStreamReader:230]: Codec Format reported: 3.
[21:01:58][I][HTTPStreamReader:240]: [ * ] Receive music info from decoder, sample_rates=44100, bits=16, ch=1
[21:01:58][I][HTTPStreamReader:243]: [ * ] Receive music info from decoder, codec_fmt=3, bps=128000, duration=0, bytes=-1192
[21:01:58][D][adf_i2s_out:141]: Set final i2s settings: 48000
[21:01:58][D][esp_audio_processors:108]: Received request from: HTTPStreamReader
[21:01:58][D][esp_audio_processors:113]: New settings: SRC: rate: 44100, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[21:01:58][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[21:01:58][D][adf_audio_element:108]: Preparing [http]...
[21:01:58][D][adf_audio_element:108]: Preparing [decoder]...
[21:01:58][D][esp-idf:000][decoder]: W (28699) AUDIO_ELEMENT: OUT-[decoder] AEL_IO_ABORT
[21:01:58]
[21:01:58][D][esp-idf:000][decoder]: W (28701) MP3_DECODER: output aborted -3
[21:01:58]
[21:01:58][D][esp-idf:000][decoder]: I (28702) MP3_DECODER: Closed
[21:01:58]
[21:01:58][D][esp_audio_sources:193]: Preparation done!
[21:01:58][D][esp_adf_pipeline:342]: wait for preparation, done
[21:01:58][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:01:58][I][adf_media_player:192]: got new pipeline state: 5, while in MP state ANNOUNCING
[21:01:58][I][adf_media_player:256]: current mp state: ANNOUNCING
[21:01:58][I][adf_media_player:257]: anouncement: yes
[21:01:58][I][adf_media_player:258]: play_intent: false
[21:01:58][I][adf_media_player:259]: current_uri_: yes
[21:01:58][D][adf_audio_element:165]: Resuming [http]...
[21:01:58][D][adf_audio_element:172]: [http] Sending resume command.
[21:01:58][D][adf_audio_element:165]: Resuming [decoder]...
[21:01:58][D][adf_audio_element:172]: [decoder] Sending resume command.
[21:01:58][D][adf_audio_element:165]: Resuming [resampler]...
[21:01:58][D][adf_audio_element:172]: [resampler] Sending resume command.
[21:01:58][D][esp-idf:000][resampler]: I (28722) AUDIO_ELEMENT: [resampler] AEL_MSG_CMD_RESUME,state:1
[21:01:58]
[21:01:58][D][adf_audio_element:191]: [resampler] Checking State, got 79
[21:02:01]0;36m[D][esp-idf:000][http]: W (31832) HTTP_STREAM: No more data,errno:0, total_bytes:52183, rlen = 0
[21:02:01]
[21:02:01][I][esp_audio_sources:033][http]: Receive http event: 7
[21:02:01][D][esp-idf:000][http]: I (31835) AUDIO_ELEMENT: IN-[http] AEL_IO_DONE,0
[21:02:01]
[21:02:01][I][esp_adf_pipeline:123]: [ http ] byte_pos: 0, total: 52183
[21:02:01][I][esp_adf_pipeline:132]: [ http ] status: 15
[21:02:01][I][esp_adf_pipeline:135]: current state: RUNNING
[21:02:01][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from RUNNING to FINISHING. (REQ: 0)
[21:02:01][I][adf_media_player:192]: got new pipeline state: 7, while in MP state ANNOUNCING
[21:02:01][I][adf_media_player:256]: current mp state: ANNOUNCING
[21:02:01][I][adf_media_player:257]: anouncement: yes
[21:02:01][I][adf_media_player:258]: play_intent: false
[21:02:01][I][adf_media_player:259]: current_uri_: yes
[21:02:01][D][esp-idf:000][decoder]: I (32067) AUDIO_ELEMENT: IN-[decoder] AEL_IO_DONE,-2
[21:02:01]
[21:02:01][D][esp-idf:000][decoder]: I (32223) MP3_DECODER: Closed
[21:02:01]
[21:02:01][I][esp_adf_pipeline:123]: [ decoder ] byte_pos: 0, total: -1192
[21:02:01][I][esp_adf_pipeline:132]: [ decoder ] status: 15
[21:02:01][I][esp_adf_pipeline:135]: current state: FINISHING
[21:02:01][D][esp-idf:000][resampler]: I (32287) AUDIO_ELEMENT: IN-[resampler] AEL_IO_DONE,-2
[21:02:01]
[21:02:01][I][esp_adf_pipeline:132]: [ resampler ] status: 15
[21:02:01][I][esp_adf_pipeline:135]: current state: FINISHING
[21:02:01][D][esp-idf:000][i2s_out]: I (32313) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2
[21:02:01]
[21:02:01][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[21:02:01][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[21:02:01][I][esp_adf_pipeline:135]: current state: FINISHING
[21:02:01][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[21:02:01][I][adf_media_player:192]: got new pipeline state: 4, while in MP state ANNOUNCING
[21:02:01][I][adf_media_player:256]: current mp state: IDLE
[21:02:01][I][adf_media_player:257]: anouncement: false
[21:02:01][I][adf_media_player:258]: play_intent: false
[21:02:01][I][adf_media_player:259]: current_uri_: yes
[21:02:03][D][voice_assistant:512]: State changed from STREAMING_RESPONSE to IDLE
[21:02:03][D][voice_assistant:518]: Desired state set to IDLE
[21:02:03][D][main:298]: Restarting wake word detection...
[21:02:03][D][micro_wake_word:399]: Resetting buffers and probabilities
[21:02:03][D][micro_wake_word:195]: State changed from IDLE to START_MICROPHONE
[21:02:03][D][micro_wake_word:107]: Starting Microphone
[21:02:03][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[21:02:03][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:02:03][D][micro_wake_word:195]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[21:02:03][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:02:03][D][adf_audio_element:108]: Preparing [i2s_in]...
[21:02:03][D][adf_audio_element:108]: Preparing [resampler]...
[21:02:03][D][adf_audio_element:108]: Preparing [pcm_reader]...
[21:02:03][D][esp_audio_sinks:054]: Set bitdepth to 32
[21:02:03][D][esp_audio_sinks:054]: Set bitdepth to 16
[21:02:03][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:02:03][D][esp_adf_pipeline:342]: wait for preparation, done
[21:02:04][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:02:04][D][adf_audio_element:165]: Resuming [i2s_in]...
[21:02:04][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[21:02:04][D][esp-idf:000][i2s_in]: I (34549) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1
[21:02:04]
[21:02:04][D][adf_audio_element:191]: [resampler] Checking State, got 15
[21:02:04][I][esp_adf_pipeline:132]: [ i2s_in ] status: 12
[21:02:04][D][adf_audio_element:191]: [pcm_reader] Checking State, got 65
[21:02:04][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[21:02:04][D][adf_audio_element:191]: [i2s_in] Checking State, got 78
[21:02:04][D][adf_audio_element:191]: [resampler] Checking State, got 78
[21:02:04][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STARTING to RUNNING. (REQ: 0)
[21:02:04][D][micro_wake_word:195]: State changed from STARTING_MICROPHONE to DETECTING_WAKE_WORD
[21:02:10][D][micro_wake_word:162]: The 'Okay Nabu' model sliding average probability is 0.988 and most recent probability is 1.000
[21:02:10][D][micro_wake_word:123]: Wake Word 'Okay Nabu' Detected
[21:02:10][D][micro_wake_word:195]: State changed from DETECTING_WAKE_WORD to STOP_MICROPHONE
[21:02:10][D][micro_wake_word:129]: Stopping Microphone
[21:02:10][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[21:02:10][D][micro_wake_word:195]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[21:02:10][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[21:02:10][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[21:02:10][D][esp-idf:000][resampler]: W (41278) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT
[21:02:10]
[21:02:10][D][esp-idf:000][i2s_in]: W (41282) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:10]
[21:02:10][D][esp-idf:000][i2s_in]: W (41283) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:10]
[21:02:10][D][esp-idf:000][i2s_in]: W (41284) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:10]
[21:02:10][D][adf_audio_element:324]: [pcm_reader] Checking State for stopping, got 3
[21:02:10][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[21:02:10][D][micro_wake_word:195]: State changed from STOPPING_MICROPHONE to IDLE
[21:02:10][D][media_player:065]: 'Media Player' - Setting
[21:02:10][D][media_player:072]:   Media URL: http://192.168.0.202:8123/local/wakeup.mp3
[21:02:10][D][esp_audio_sources:098]: Set new uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:02:10][D][adf_media_player:057]: Got control call in state IDLE
[21:02:10][D][adf_media_player:058]: req_track stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:02:10][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:02:10][D][voice_assistant:512]: State changed from IDLE to START_MICROPHONE
[21:02:10][D][voice_assistant:518]: Desired state set to START_PIPELINE
[21:02:11][D][voice_assistant:222]: Starting Microphone
[21:02:11][D][esp_adf_pipeline.microphone:025]: start request while ine state 0
[21:02:11][D][esp_adf_pipeline:060]: Starting request, current state STOPPED
[21:02:11][D][voice_assistant:512]: State changed from START_MICROPHONE to STARTING_MICROPHONE
[21:02:11][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:02:11][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from STOPPED to PREPARING. (REQ: 0)
[21:02:11][I][adf_media_player:192]: got new pipeline state: 3, while in MP state IDLE
[21:02:11][D][adf_i2s_out:141]: Set final i2s settings: 48000
[21:02:11][D][esp_audio_processors:124]: Current settings: SRC: rate: 44100, ch: 1 bits: 16, DST: rate: 48000, ch: 1, bits 16
[21:02:11][I][adf_media_player:256]: current mp state: PLAYING
[21:02:11][I][adf_media_player:257]: anouncement: false
[21:02:11][I][adf_media_player:258]: play_intent: false
[21:02:11][I][adf_media_player:259]: current_uri_: yes
[21:02:11][D][adf_audio_element:108]: Preparing [i2s_in]...
[21:02:11][D][esp_audio_sources:103]: Prepare elements called (initial_call)!
[21:02:11][D][esp_audio_sources:137]: Use fixed settings: no
[21:02:11][D][esp_audio_sources:138]: Streamer status: 6
[21:02:11][D][esp_audio_sources:139]: decoder status: 6
[21:02:11][D][esp_audio_sources:140]: stream uri: http://192.168.0.202:8123/local/wakeup.mp3
[21:02:11][D][adf_audio_element:108]: Preparing [http]...
[21:02:11][D][adf_audio_element:108]: Preparing [decoder]...
[21:02:11][D][adf_audio_element:108]: Preparing [resampler]...
[21:02:11][D][adf_audio_element:108]: Preparing [resampler]...
[21:02:11][D][adf_audio_element:108]: Preparing [pcm_reader]...
[21:02:11][D][adf_audio_element:108]: Preparing [i2s_out]...
[21:02:11][D][esp_audio_sinks:054]: Set bitdepth to 32
[21:02:11][D][esp_audio_sinks:054]: Set bitdepth to 16
[21:02:11][D][esp_audio_processors:124]: Current settings: SRC: rate: 48000, ch: 1 bits: 32, DST: rate: 16000, ch: 1, bits 16
[21:02:11][D][esp_adf_pipeline:342]: wait for preparation, done
[21:02:11][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from PREPARING to STARTING. (REQ: 0)
[21:02:11][D][adf_audio_element:165]: Resuming [i2s_in]...
[21:02:11][D][adf_audio_element:172]: [i2s_in] Sending resume command.
[21:02:11][D][esp-idf:000][i2s_in]: I (41359) AUDIO_ELEMENT: [i2s_in] AEL_MSG_CMD_RESUME,state:1
[21:02:11]
[21:02:11][D][adf_audio_element:191]: [i2s_in] Checking State, got 79
[21:02:11][I][esp_adf_pipeline:132]: [ i2s_in ] status: 12
[21:02:11][D][adf_audio_element:191]: [http] Checking State, got 15
[21:02:11][D][esp-idf:000][resampler]: I (41363) RSP_FILTER: sample rate of source data : 48000, channel of source data : 1, sample rate of destination data : 16000, channel of destination data : 1
[21:02:11]
[21:02:11][D][voice_assistant:512]: State changed from START_PIPELINE to STARTING_PIPELINE
[21:02:11][I][esp_adf_pipeline:132]: [ resampler ] status: 12
[21:02:11][I][esp_audio_sources:033][http]: Receive http event: 1
[21:02:11][D][voice_assistant:533]: Client started, streaming microphone
[21:02:11][D][voice_assistant:512]: State changed from STARTING_PIPELINE to STREAMING_MICROPHONE
[21:02:11][D][voice_assistant:518]: Desired state set to STREAMING_MICROPHONE
[21:02:11][D][voice_assistant:635]: Event Type: 1
[21:02:11][D][voice_assistant:638]: Assist Pipeline running
[21:02:11][D][adf_audio_element:191]: [http] Checking State, got 79
[21:02:11][D][adf_audio_element:191]: [decoder] Checking State, got 79
[21:02:11][D][voice_assistant:635]: Event Type: 3
[21:02:11][D][voice_assistant:649]: STT started
[21:02:11][D][main:817]: Listening for commands...
[21:02:11][D][light:036]: 'RGB LED' Setting:
[21:02:11][D][light:047]:   State: ON
[21:02:11][D][light:051]:   Brightness: 100%
[21:02:11][D][light:059]:   Red: 100%, Green: 100%, Blue: 100%
[21:02:11][D][light:109]:   Effect: 'listening'
[21:02:11][W][micro_wake_word:183]: Wa[D][esp-idf:000][i2s_out]: I (42446) AUDIO_ELEMENT: IN-[i2s_out] AEL_IO_DONE,-2
[21:02:11]
[21:02:11][I][esp_adf_pipeline:123]: [ i2s_out ] byte_pos: 0, total: 0
[21:02:11][I][esp_adf_pipeline:132]: [ i2s_out ] status: 15
[21:02:11][I][esp_adf_pipeline:135]: current state: FINISHING
[21:02:11][D][esp_adf_pipeline:448]: [MediaPlayer] Pipeline changed from FINISHING to STOPPED. (REQ: 1)
[21:02:11][I][adf_media_player:192]: got new pipeline state: 4, while in MP state PLAYING
[21:02:11][I][adf_media_player:256]: current mp state: IDLE
[21:02:12][I][adf_media_player:257]: anouncement: false
[21:02:12][I][adf_media_player:258]: play_intent: false
[21:02:12][I][adf_media_player:259]: current_uri_: yes
[21:02:12][D][voice_assistant:635]: Event Type: 11
[21:02:12][D][voice_assistant:795]: Starting STT by VAD
[21:02:13][D][voice_assistant:635]: Event Type: 12
[21:02:13][D][voice_assistant:799]: STT by VAD end
[21:02:13][D][voice_assistant:512]: State changed from STREAMING_MICROPHONE to STOP_MICROPHONE
[21:02:13][D][voice_assistant:518]: Desired state set to AWAITING_RESPONSE
[21:02:13][D][esp_adf_pipeline:070]: Called 'stop' while in RUNNING state.
[21:02:13][D][voice_assistant:512]: State changed from STOP_MICROPHONE to STOPPING_MICROPHONE
[21:02:13][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from RUNNING to ABORTING. (REQ: 1)
[21:02:13][D][main:328]: VAD end ...
[21:02:13][D][light:036]: 'RGB LED' Setting:
[21:02:13][D][light:051]:   Brightness: 70%
[21:02:13][D][light:059]:   Red: 0%, Green: 20%, Blue: 100%
[21:02:13][D][light:109]:   Effect: 'processing'
[21:02:13][D][adf_audio_element:324]: [i2s_in] Checking State for stopping, got 3
[21:02:13][D][esp-idf:000][resampler]: W (44111) AUDIO_ELEMENT: IN-[resampler] AEL_IO_ABORT
[21:02:13]
[21:02:13][D][esp-idf:000][i2s_in]: W (44113) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:13]
[21:02:13][D][esp-idf:000][i2s_in]: W (44115) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:13]
[21:02:13][D][esp-idf:000][i2s_in]: W (44116) AUDIO_ELEMENT: OUT-[i2s_in] AEL_IO_ABORT
[21:02:13]
[21:02:13][D][esp_adf_pipeline:448]: [ADFMicrophone] Pipeline changed from ABORTING to STOPPED. (REQ: 1)
[21:02:13][D][voice_assistant:512]: State changed from STOPPING_MICROPHONE to AWAITING_RESPONSE
[21:02:15][D][voice_assistant:635]: Event Type: 4
[21:02:15][D][voice_assistant:663]: Speech recognised as: "Welcher Tag ist morgen?"
[21:02:15][D][voice_assistant:635]: Event Type: 5
[21:02:15][D][voice_assistant:668]: Intent started
[21:02:15]Guru Meditation Error: Core  1 panic'ed (Interrupt wdt timeout on CPU1). 
[21:02:15]
[21:02:15]Core  1 register dump:
[21:02:15]PC      : 0x40376045  PS      : 0x00050034  A0      : 0x403778dc  A1      : 0x3fc99680  
[21:02:15]A2      : 0x3d801120  A3      : 0x00000003  A4      : 0x60023000  A5      : 0x4037d416  
[21:02:15]A6      : 0x2bef667c  A7      : 0x000fffff  A8      : 0x6003f11f  A9      : 0x00000000  
[21:02:15]A10     : 0x3d801120  A11     : 0x00000001  A12     : 0x8201a55a  A13     : 0x3fcee3e0  
[21:02:15]A14     : 0x3fcf5970  A15     : 0x3fcf5520  SAR     : 0x0000000a  EXCCAUSE: 0x00000006  
[21:02:15]EXCVADDR: 0x00000000  LBEG    : 0x40056f5c  LEND    : 0x40056f72  LCOUNT  : 0x00000000  
[21:02:15]Core  1 was running in ISR context:
[21:02:16]EPC1    : 0x40041a6b  EPC2    : 0x00000000  EPC3    : 0x00000000  EPC4    : 0x40376045
[21:02:16]
[21:02:16]
[21:02:16]Backtrace: 0x40376042:0x3fc99680 0x403778d9:0x3fc996b0 0x40382119:0x3fcee4a0 0x403772c8:0x3fcee4d0 0x403754bf:0x3fcee4f0 0x42018d4e:0x3fcee510 0x42017b49:0x3fcee530 0x4201b57e:0x3fcee560 0x4200b17e:0x3fcee580
[21:02:16]
[21:02:16]
[21:02:16]Core  0 register dump:
[21:02:16]PC      : 0x420ddfe6  PS      : 0x00060c34  A0      : 0x82057e1c  A1      : 0x3fcf4360  
[21:02:16]A2      : 0x00000000  A3      : 0x80000001  A4      : 0x80380e0d  A5      : 0x3fcf4280  
[21:02:16]A6      : 0x00060023  A7      : 0x00000003  A8      : 0x00000000  A9      : 0x00000000  
[21:02:16]A10     : 0x3fcf4c68  A11     : 0x80000001  A12     : 0x80380e0d  A13     : 0x3d811170  
[21:02:16]A14     : 0x00060023  A15     : 0x00000003  SAR     : 0x0000001f  EXCCAUSE: 0x00000006  
[21:02:16]EXCVADDR: 0x00000000  LBEG    : 0x00000000  LEND    : 0x00000000  LCOUNT  : 0x00000000  
[21:02:16]
[21:02:16]
[21:02:16]Backtrace: 0x420ddfe3:0x3fcf4360 0x42057e19:0x3fcf4380 0x4037e8f0:0x3fcf43a0
[21:02:16]
[21:02:16]
[21:02:16]
[21:02:16]
[21:02:16]ELF file SHA256: 3f0140ff85637815
[21:02:16]
[21:02:16]Rebooting...
[21:02:16]ESP-ROM:esp32s3-20210327
[21:02:16]Build:Mar 27 2021
[21:02:16]rst:0xc (RTC_SW_CPU_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
[21:02:16]Saved PC:0x4205861a
[21:02:16]SPIWP:0xee
[21:02:16]mode:DIO, clock div:1
[21:02:16]load:0x3fce3808,len:0x16c4
[21:02:16]load:0x403c9700,len:0xbc0
[21:02:16]load:0x403cc700,len:0x2e90
[21:02:16]entry 0x403c9950
[21:02:16]I (24) boot: ESP-IDF 4.4.8 2nd stage bootloader
[21:02:16]I (24) boot: compile time 17:59:10
[21:02:16]I (24) boot: Multicore bootloader
[21:02:16]I (26) boot: chip revision: v0.2
[21:02:16]I (30) boot.esp32s3: Boot SPI Speed : 80MHz
[21:02:16]I (35) boot.esp32s3: SPI Mode       : DIO
[21:02:16]I (39) boot.esp32s3: SPI Flash Size : 16MB
[21:02:16]I (44) boot: Enabling RNG early entropy source...
[21:02:16]I (49) boot: Partition Table:
[21:02:16]I (53) boot: ## Label            Usage          Type ST Offset   Length
[21:02:16]I (60) boot:  0 otadata          OTA data         01 00 00009000 00002000
[21:02:16]I (68) boot:  1 phy_init         RF data          01 01 0000b000 00001000
[21:02:16]I (75) boot:  2 app0             OTA app          00 10 00010000 007c0000
[21:02:16]I (83) boot:  3 app1             OTA app          00 11 007d0000 007c0000
[21:02:16]I (90) boot:  4 nvs              WiFi data        01 02 00f90000 0006d000
[21:02:16]I (98) boot: End of partition table
[21:02:16]I (102) esp_image: segment 0: paddr=00010020 vaddr=3c0f0020 size=47630h (292400) map
[21:02:16]I (163) esp_image: segment 1: paddr=00057658 vaddr=3fc98700 size=03f90h ( 16272) load
[21:02:16]I (167) esp_image: segment 2: paddr=0005b5f0 vaddr=40374000 size=04a28h ( 18984) load
[21:02:16]I (173) esp_image: segment 3: paddr=00060020 vaddr=42000020 size=e0274h (918132) map
[21:02:16]I (343) esp_image: segment 4: paddr=0014029c vaddr=40378a28 size=0fcd8h ( 64728) load
[21:02:16]I (365) boot: Loaded app from partition at offset 0x10000
[21:02:16]I (366) boot: Disabling RNG early entropy source...
[21:02:16]I (366) cpu_start: Multicore app
[21:02:17]I (370) opi psram: vendor id : 0x0d (AP)
[21:02:17]I (374) opi psram: dev id    : 0x02 (generation 3)
[21:02:17]I (380) opi psram: density   : 0x03 (64 Mbit)
[21:02:17]I (385) opi psram: good-die  : 0x01 (Pass)
[21:02:17]I (389) opi psram: Latency   : 0x01 (Fixed)
[21:02:17]I (394) opi psram: VCC       : 0x01 (3V)
[21:02:17]I (399) opi psram: SRF       : 0x01 (Fast Refresh)
[21:02:17]I (404) opi psram: BurstType : 0x01 (Hybrid Wrap)
[21:02:17]I (410) opi psram: BurstLen  : 0x01 (32 Byte)
[21:02:17]I (415) opi psram: Readlatency  : 0x02 (10 cycles@Fixed)
[21:02:17]I (421) opi psram: DriveStrength: 0x00 (1/1)
[21:02:17]I (426) MSPI Timing: PSRAM timing tuning index: 5
[21:02:17]I (431) spiram: Found 64MBit SPI RAM device
[21:02:17]I (436) spiram: SPI RAM mode: sram 80m
[21:02:17]I (440) spiram: PSRAM initialized, cache is in normal (1-core) mode.
[21:02:17]I (447) cpu_start: Pro cpu up.
[21:02:17]I (451) cpu_start: Starting app cpu, entry point is 0x40376534
[21:02:17]I (440) cpu_start: App cpu up.
[21:02:17]I (884) spiram: SPI SRAM memory test OK
[21:02:17]I (892) cpu_start: Pro cpu start user code
[21:02:17]I (893) cpu_start: cpu freq: 240000000
[21:02:17]I (893) cpu_start: Application information:
[21:02:17]I (893) cpu_start: Project name:     diy-voice-assistant
[21:02:17]I (893) cpu_start: App version:      2024.11.1
[21:02:17]I (893) cpu_start: Compile time:     Nov 25 2024 13:45:51
[21:02:17]I (894) cpu_start: ELF file SHA256:  3f0140ff85637815...
[21:02:17]I (894) cpu_start: ESP-IDF:          4.4.8
[21:02:17]I (894) cpu_start: Min chip rev:     v0.0
[21:02:17]I (894) cpu_start: Max chip rev:     v0.99 
[21:02:17]I (894) cpu_start: Chip rev:         v0.2
[21:02:17]I (895) heap_init: Initializing. RAM available for dynamic allocation:
[21:02:17]I (895) heap_init: At 3FCA0BE0 len 00048B30 (290 KiB): D/IRAM
[21:02:17]I (895) heap_init: At 3FCE9710 len 00005724 (21 KiB): STACK/DIRAM
[21:02:17]I (895) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
[21:02:17]I (896) heap_init: At 600FE000 len 00002000 (8 KiB): RTCRAM
[21:02:17]I (896) spiram: Adding pool of 8192K of external SPI memory to heap allocator
[21:02:17]I (897) spi_flash: detected chip: boya
[21:02:17]I (897) spi_flash: flash io: dio
[21:02:17]I (898) sleep: Configure to isolate all GPIO pins in sleep state
[21:02:17]I (898) sleep: Enable automatic switching of GPIO sleep configuration
[21:02:17]I (899) cpu_start: Starting scheduler on PRO CPU.
[21:02:17]I (0) cpu_start: Starting scheduler on APP CPU.
[21:02:17][I][logger:171]: Log initialized
[21:02:17][C][safe_mode:079]: There have been 2 suspected unsuccessful boot attempts
[21:02:17][D][esp32.preferences:114]: Saving 1 preferences to flash...
[21:02:17][D][esp32.preferences:143]: Saving 1 preferences to flash: 0 cached, 1 written, 0 failed
[21:02:17][I][app:029]: Running through setup()...
[21:02:17][C][esp32_rmt_led_strip:021]: Setting up ESP32 LED Strip...
[21:02:17][C][light:021]: Setting up light 'RGB LED'...
[21:02:17][D][light:036]: 'RGB LED' Setting:
[21:02:17][D][light:041]:   Color mode: RGB
[21:02:17][D][light:085]:   Transition length: 0.1s
[21:02:17][D][template.switch:046]:   Restored state ON
[21:02:17][D][switch:012]: 'Use Wake Word' Turning ON.
[21:02:17][D][switch:055]: 'Use Wake Word': Sending state ON
[21:02:17][D][template.switch:046]:   Restored state ON
[21:02:17][D][switch:012]: 'Wake Word Listening Light' Turning ON.
[21:02:17][D][switch:055]: 'Wake Word Listening Light': Sending state ON
[21:02:17][D][light:036]: 'RGB LED' Setting:
[21:02:17][D][light:085]:   Transition length: 0.1s
[21:02:17][D][template.switch:046]:   Restored state OFF
[21:02:17][D][switch:016]: 'Pipeline' Turning OFF.
[21:02:17][D][switch:055]: 'Pipeline': Sending state OFF
[21:02:17][D][media_player:065]: 'Media Player' - Setting
[21:02:17][D][media_player:069]:   Command: STOP
[21:02:17][D][esp_adf_pipeline:070]: Called 'stop' while in UNINITIALIZED state.
[21:02:17][D][light:036]: 'RGB LED' Setting:
[21:02:17][D][light:047]:   State: ON
[21:02:17][D][light:059]:   Red: 100%, Green: 0%, Blue: 0%
[21:02:17][D][light:109]:   Effect: 'slow_pulse'
[21:02:17][D][media_player:065]: 'Media Player' - Setting
[21:02:17][D][media_player:075]:   Volume: 0.70
[21:02:17][D][esp_audio_processors:124]: Current settings: SRC: rate: 16000, ch: 2 bits: 16, DST: rate: 16000, ch: 2, bits 16
[21:02:18][C][i2s_audio:024]: Setting up I2S Audio...
[21:02:18][C][i2s_audio:024]: Setting up I2S Audio...
[21:02:18][C][wifi:048]: Setting up WiFi...
[21:02:18][D][esp-idf:000]: I (196) pp: pp rom version: e7ae62f
[21:02:18]
[21:02:18][D][esp-idf:000]: I (197) net80211: net80211 rom version: e7ae62f
[21:02:18]
[21:02:18][D][esp-idf:000]: I (199) wifi:
[21:02:18][D][esp-idf:000]: wifi driver task: 3fca60d0, prio:23, stack:6656, core=0
[21:02:18][D][esp-idf:000]: 
[21:02:18]
[21:02:18][D][esp-idf:000][wifi]: I (1301) system_api: Base[W][micro_wake_word:151]: Wake word detection can't start as the component hasn't been setup yet
[21:02:18][D][light:036]: 'RGB LED' Setting:
[21:02:18][D][light:047]:   State: OFF
[21:02:18][D][light:085]:   Transition length: 0.1s
[21:02:18][D][light:091]:   Effect: 'None'
[21:02:23][D][esp-idf:000][wifi]: I (7942) wifi:
[21:02:23][D][esp-idf:000][wifi]: state: run -> init (fc0)
[21:02:23][D][esp-idf:000][wifi]: 
[21:02:23]
[21:02:23][D][esp-idf:000][wifi]: I (7945) wifi:
[21:02:23][D][esp-idf:000][wifi]: new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
[21:02:23][D][esp-idf:000][wifi]: 
[21:02:23]
[21:02:23][W][wifi_esp32:704]: Event: Disconnected ssid='PYUR CB8FB' bssid=34:2C:C4:6A:47:44[redacted] reason='4-Way Handshake Timeout'
[21:02:23][W][wifi:653]: Error while connecting to network.
[21:02:23][D][wifi:697]: Retrying with hidden networks...
[21:02:23][I][wifi:313]: WiFi Connecting to 'PYUR CB8FB'...
[21:02:23][D][esp-idf:000][wifi]: I (7967) wifi:
[21:02:23][D][esp-idf:000][wifi]: new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
[21:02:23][D][esp-idf:000][wifi]: 
[21:02:23]
[21:02:23][D][esp-idf:000][wifi]: I (7970) wifi:
[21:02:23][D][esp-idf:000][wifi]: state: init -> auth (b0)
[21:02:23][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (7975) wifi:
[21:02:24][D][esp-idf:000][wifi]: state: auth -> assoc (0)
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (7989) wifi:
[21:02:24][D][esp-idf:000][wifi]: state: assoc -> run (10)
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (8123) wifi:
[21:02:24][D][esp-idf:000][wifi]: connected with PYUR CB8FB, aid = 1, channel 11, BW20, bssid = 34:2c:c4:6a:47:44
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (8125) wifi:
[21:02:24][D][esp-idf:000][wifi]: security: WPA2-PSK, phy: bgn, rssi: -46
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (8127) wifi:
[21:02:24][D][esp-idf:000][wifi]: pm start, type: 1
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (8128) wifi:
[21:02:24][D][esp-idf:000][wifi]: set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 0, mt_pti: 25000, mt_time: 10000
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][D][esp-idf:000][wifi]: I (8131) wifi:
[21:02:24][D][esp-idf:000][wifi]: AP's beacon interval = 102400 us, DTIM period = 1
[21:02:24][D][esp-idf:000][wifi]: 
[21:02:24]
[21:02:24][I][wifi:617]: WiFi Connected!
[21:02:24][C][wifi:428]:   Local MAC: 24:EC:4A:01:C3:B0
[21:02:24][C][wifi:433]:   SSID: 'PYUR CB8FB'[redacted]
[21:02:24][C][wifi:436]:   IP Address: 192.168.0.203
[21:02:24][C][wifi:440]:   BSSID: 34:2C:C4:6A:47:44[redacted]
[21:02:24][C][wifi:441]:   Hostname: 'diy-voice-assistant-01c3b0'
[21:02:24][C][wifi:443]:   Signal strength: -46 dB ▂▄▆█
[21:02:24][C][wifi:447]:   Channel: 11
[21:02:24][C][wifi:448]:   Subnet: 255.255.255.0
[21:02:24][C][wifi:449]:   Gateway: 192.168.0.1
[21:02:25][C][wifi:450]:   DNS1: 0.0.0.0
[21:02:25][C][wifi:451]:   DNS2: 0.0.0.0
[21:02:25][C][wifi:453]:   BTM: disabled
[21:02:25][C][wifi:454]:   RRM: enabled
[21:02:25][D][light:036]: 'RGB LED' Setting:
[21:02:25][D][light:047]:   State: ON
[21:02:25][D][light:059]:   Red: 0%, Green: 100%, Blue: 0%
[21:02:25][D][light:109]:   Effect: 'pulse'
[21:02:25][C][api:026]: Setting up Home Assistant API server...
[21:02:25][C][micro_wake_word:062]: Setting up microWakeWord...
[21:02:25][C][micro_wake_word:069]: Micro Wake Word initialized
[21:02:25][I][app:062]: setup() finished successfully!
[21:02:25][W][component:170]: Component wifi cleared Warning flag
[21:02:25][W][component:157]: Component api set Warning flag: unspecified
[21:02:25][I][app:100]: ESPHome version 2024.11.1 compiled on Nov 26 2024, 19:57:47
[21:02:25][C][wifi:600]: WiFi:
[21:02:25][C][wifi:428]:   Local MAC: 24:EC:4A:01:C3:B0
[21:02:25][C][wifi:433]:   SSID: 'PYUR CB8FB'[redacted]
[21:02:25][C][wifi:436]:   IP Address: 192.168.0.203
[21:02:25][C][wifi:440]:   BSSID: 34:2C:C4:6A:47:44[redacted]
[21:02:25][C][wifi:441]:   Hostname: 'diy-voice-assistant-01c3b0'
[21:02:25][C][wifi:443]:   Signal strength: -46 dB ▂▄▆█
[21:02:25][C][wifi:447]:   Channel: 11
[21:02:25][C][wifi:448]:   Subnet: 255.255.255.0
[21:02:25][C][wifi:449]:   Gateway: 192.168.0.1
[21:02:25][C][wifi:450]:   DNS1: 0.0.0.0
[21:02:25][C][wifi:451]:   DNS2: 0.0.0.0
[21:02:25][C][wifi:453]:   BTM: disabled
[21:02:25][C][wifi:454]:   RRM: enabled
[21:02:25][C][logger:185]: Logger:
[21:02:25][C][logger:186]:   Level: DEBUG
[21:02:25][C][logger:188]:   Log Baud Rate: 115200
[21:02:25][C][logger:189]:   Hardware UART: USB_SERIAL_JTAG
[21:02:25][C][esp32_rmt_led_strip:187]: ESP32 RMT LED Strip:
[21:02:25][C][esp32_rmt_led_strip:188]:   Pin: 48
[21:02:25][C][esp32_rmt_led_strip:189]:   Channel: 0
[21:02:25][C][esp32_rmt_led_strip:214]:   RGB Order: GRB
[21:02:25][C][esp32_rmt_led_strip:215]:   Max refresh rate: 0
[21:02:25][C][esp32_rmt_led_strip:216]:   Number of LEDs: 1
[21:02:25][C][light:092]: Light 'RGB LED'
[21:02:25][C][light:094]:   Default Transition Length: 0.1s
[21:02:25][C][light:095]:   Gamma Correct: 2.80
[21:02:25][C][template.switch:068]: Template Switch 'Use Wake Word'
[21:02:25][C][template.switch:091]:   Restore Mode: restore defaults to ON
[21:02:25][C][template.switch:057]:   Optimistic: YES
[21:02:25][C][template.switch:068]: Template Switch 'Wake Word Listening Light'
[21:02:25][C][template.switch:091]:   Restore Mode: restore defaults to ON
[21:02:25][C][template.switch:057]:   Optimistic: YES
[21:02:25][C][template.switch:068]: Template Switch 'Pipeline'
[21:02:25][C][template.switch:091]:   Restore Mode: restore defaults to OFF
[21:02:25][C][template.switch:057]:   Optimistic: YES
[21:02:25][C][psram:020]: PSRAM:
[21:02:29][C][psram:021]:   Available: YES�[D][light:036]: 'RGB LED' Setting:
[21:02:29][D][light:047]:   State: OFF
[21:02:29][D][light:085]:   Transition length: 0.1s

0 replies

rmeissn · 2024-12-27T16:17:07Z

rmeissn
Dec 27, 2024
Author

I guess my issue was related to bad boards from China. With a proper Onju board, everything works as it should.

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Uh oh!

Uh oh!

MicroWakeWord version reboots on every second voice command #98

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{editor}}'s edit

{{editor}}'s edit

Uh oh!

Replies: 2 comments

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{title}}

Uh oh!

Select a reply

Uh oh!

Uh oh!

MicroWakeWord version reboots on every second voice command #98

Uh oh!

Uh oh!

rmeissn Nov 24, 2024

Flavor

Checklist

Describe the issue

Reproduction steps

Debug logs

Code

Replies: 2 comments

Uh oh!

rmeissn Nov 27, 2024 Author

Uh oh!

rmeissn Dec 27, 2024 Author

rmeissn
Nov 24, 2024

rmeissn
Nov 27, 2024
Author

rmeissn
Dec 27, 2024
Author