Satellite Setup: TTS or rather Audio Playing not working

[Mondmonarch]

Hello there,

i’ve followed the guide on setting up a base station and a satellite with mqtt.
Unfortunately I don’t get any sound feedback.

According to the mqtt broker, the base station should play the feedback (Topic hermes/tts/#):

hermes/tts/say {"text": "Okay, ich breche ab", "siteId": "himbipi-satellite-1", "lang": null, "id": "796d388e-3245-4e0a-b84a-4a0addd32628", "sessionId": "himbipi-satellite-1-jarvis_raspberry-pi-830471ff-e525-4ee3-a56f-d49867557802", "volume": null}
hermes/tts/sayFinished {"siteId": "himbipi-satellite-1", "id": "796d388e-3245-4e0a-b84a-4a0addd32628", "sessionId": "himbipi-satellite-1-jarvis_raspberry-pi-830471ff-e525-4ee3-a56f-d49867557802"}

But the docker logs of the satellite are showing the following error:

[DEBUG:2023-04-04 23:20:49,104] rhasspywake_porcupine_hermes: -> HotwordDetected(model_id='/profiles/de/porcupine/jarvis_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.5, site_id='himbipi-satellite-1', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
[DEBUG:2023-04-04 23:20:49,106] rhasspywake_porcupine_hermes: Publishing 250 bytes(s) to hermes/hotword/jarvis_raspberry-pi/detected
[DEBUG:2023-04-04 23:20:49,127] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,128] rhasspywake_porcupine_hermes: Disabled
[DEBUG:2023-04-04 23:20:49,122] rhasspyserver_hermes: <- HotwordDetected(model_id='/profiles/de/porcupine/jarvis_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.5, site_id='himbipi-satellite-1', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
[WARNING:2023-04-04 23:20:49,124] rhasspyserver_hermes: Dialogue management is disabled. ASR will NOT be automatically enabled.
[DEBUG:2023-04-04 23:20:49,148] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(42088 byte(s))
[DEBUG:2023-04-04 23:20:49,150] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'hw:CARD=Device,DEV=0']
[DEBUG:2023-04-04 23:20:49,451] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='c689ab41-fcd6-4fe4-9117-04ab229e1c74', session_id='c689ab41-fcd6-4fe4-9117-04ab229e1c74')
[DEBUG:2023-04-04 23:20:49,452] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/himbipi-satellite-1/playFinished
[DEBUG:2023-04-04 23:20:49,467] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,467] rhasspywake_porcupine_hermes: Enabled
[DEBUG:2023-04-04 23:20:49,475] rhasspywake_porcupine_hermes: Receiving audio himbipi-satellite-1
[DEBUG:2023-04-04 23:20:49,484] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:49,485] rhasspywake_porcupine_hermes: Disabled
[DEBUG:2023-04-04 23:20:49,493] rhasspymicrophone_cli_hermes: <- AsrStartListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='jarvis_raspberry-pi', intent_filter=None)
[DEBUG:2023-04-04 23:20:49,494] rhasspymicrophone_cli_hermes: Disable UDP output
[DEBUG:2023-04-04 23:20:52,031] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,032] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(95312 byte(s))
[DEBUG:2023-04-04 23:20:52,032] rhasspywake_porcupine_hermes: Disabled
[DEBUG:2023-04-04 23:20:52,034] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'hw:CARD=Device,DEV=0']
[ERROR:2023-04-04 23:20:52,035] rhasspyspeakers_cli_hermes: maybe_change_volume
Traceback (most recent call last):
  File "/usr/lib/rhasspy/rhasspy-speakers-cli-hermes/rhasspyspeakers_cli_hermes/__init__.py", line 197, in maybe_change_volume
    info_data = wavchunk.get_chunk(wav_in_io)
  File "/usr/lib/rhasspy/.venv/lib/python3.7/site-packages/wavchunk/__init__.py", line 170, in get_chunk
    chunk_size = read_size(wav_file)
  File "/usr/lib/rhasspy/.venv/lib/python3.7/site-packages/wavchunk/__init__.py", line 41, in read_size
    return struct.unpack_from("<L", file.read(4))[0]
struct.error: unpack_from requires a buffer of at least 4 bytes
[DEBUG:2023-04-04 23:20:52,580] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='aa3c96ec-b0b9-4d93-9731-7596311d1078', session_id='aa3c96ec-b0b9-4d93-9731-7596311d1078')
[DEBUG:2023-04-04 23:20:52,581] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/himbipi-satellite-1/playFinished
[DEBUG:2023-04-04 23:20:52,602] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,603] rhasspywake_porcupine_hermes: Still disabled: {<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>}
[DEBUG:2023-04-04 23:20:52,607] rhasspymicrophone_cli_hermes: <- AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,608] rhasspymicrophone_cli_hermes: Enable UDP output
[DEBUG:2023-04-04 23:20:52,620] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:52,620] rhasspywake_porcupine_hermes: Enabled
[DEBUG:2023-04-04 23:20:52,673] rhasspyserver_hermes: <- NluIntent(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', custom_data='jarvis_raspberry-pi', asr_tokens=[[AsrToken(value='Nein', confidence=1.0, range_start=0, range_end=4, time=None)]], asr_confidence=1.0, raw_input='nein', wakeword_id='jarvis_raspberry-pi', lang=None)
[DEBUG:2023-04-04 23:20:52,681] rhasspywake_porcupine_hermes: Receiving audio himbipi-satellite-1
[DEBUG:2023-04-04 23:20:52,710] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,710] rhasspywake_porcupine_hermes: Disabled
[DEBUG:2023-04-04 23:20:52,800] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(52908 byte(s))
[DEBUG:2023-04-04 23:20:52,801] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'hw:CARD=Device,DEV=0']
aplay: set_params:1345: Channels count non available
[ERROR:2023-04-04 23:20:52,842] rhasspyspeakers_cli_hermes: handle_play
Traceback (most recent call last):
  File "/usr/lib/rhasspy/rhasspy-speakers-cli-hermes/rhasspyspeakers_cli_hermes/__init__.py", line 88, in handle_play
    subprocess.run(self.play_command, input=wav_bytes, check=True)
  File "/usr/lib/python3.7/subprocess.py", line 487, in run
    output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['aplay', '-q', '-t', 'wav', '-D', 'hw:CARD=Device,DEV=0']' returned non-zero exit status 1.
[DEBUG:2023-04-04 23:20:52,851] rhasspyspeakers_cli_hermes: -> AudioPlayError(error="Command '['aplay', '-q', '-t', 'wav', '-D', 'hw:CARD=Device,DEV=0']' returned non-zero exit status 1.", site_id='himbipi-satellite-1', context='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='db899083-47b4-457e-8f4e-7f95ab53b439')
[DEBUG:2023-04-04 23:20:52,853] rhasspyspeakers_cli_hermes: Publishing 251 bytes(s) to hermes/error/audioServer/play
[DEBUG:2023-04-04 23:20:52,858] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='db899083-47b4-457e-8f4e-7f95ab53b439')
[DEBUG:2023-04-04 23:20:52,859] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/himbipi-satellite-1/playFinished
[DEBUG:2023-04-04 23:20:52,918] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,918] rhasspywake_porcupine_hermes: Enabled
[DEBUG:2023-04-04 23:20:52,924] rhasspymicrophone_cli_hermes: <- AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,925] rhasspymicrophone_cli_hermes: Enable UDP output
[DEBUG:2023-04-04 23:20:52,929] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:52,930] rhasspywake_porcupine_hermes: Enabled
[DEBUG:2023-04-04 23:20:52,932] rhasspywake_porcupine_hermes: Receiving audio himbipi-satellite-1

On the base station, the docker logs are showing:

[DEBUG:2023-04-04 23:20:49,111] rhasspydialogue_hermes: <- HotwordDetected(model_id='/profiles/de/porcupine/jarvis_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.5, site_id='himbipi-satellite-1', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
[DEBUG:2023-04-04 23:20:49,113] rhasspydialogue_hermes: Playing sound /profiles/de/wav/start_of_input.wav
[DEBUG:2023-04-04 23:20:49,116] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,117] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/hotword/toggleOff
[DEBUG:2023-04-04 23:20:49,120] rhasspydialogue_hermes: -> AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,121] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/asr/toggleOff
[DEBUG:2023-04-04 23:20:49,122] rhasspydialogue_hermes: -> AudioPlayBytes(42088 byte(s)) to hermes/audioServer/himbipi-satellite-1/playBytes/c689ab41-fcd6-4fe4-9117-04ab229e1c74
[DEBUG:2023-04-04 23:20:49,124] rhasspydialogue_hermes: Waiting for playFinished (id=c689ab41-fcd6-4fe4-9117-04ab229e1c74, timeout=1.203378684807256)
[DEBUG:2023-04-04 23:20:49,128] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,129] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.PLAY_AUDIO)
[DEBUG:2023-04-04 23:20:49,455] rhasspydialogue_hermes: <- AudioPlayFinished(id='c689ab41-fcd6-4fe4-9117-04ab229e1c74', session_id='c689ab41-fcd6-4fe4-9117-04ab229e1c74')
[DEBUG:2023-04-04 23:20:49,455] rhasspytts_cli_hermes: <- AudioPlayFinished(id='c689ab41-fcd6-4fe4-9117-04ab229e1c74', session_id='c689ab41-fcd6-4fe4-9117-04ab229e1c74')
[DEBUG:2023-04-04 23:20:49,460] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,460] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/hotword/toggleOn
[DEBUG:2023-04-04 23:20:49,463] rhasspydialogue_hermes: -> AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,464] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/asr/toggleOn
[DEBUG:2023-04-04 23:20:49,469] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:49,466] rhasspydialogue_hermes: Starting new session (id=himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a)
[DEBUG:2023-04-04 23:20:49,469] rhasspyasr_kaldi_hermes: Enabled
[DEBUG:2023-04-04 23:20:49,473] rhasspydialogue_hermes: -> DialogueSessionStarted(session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', site_id='himbipi-satellite-1', custom_data='jarvis_raspberry-pi', lang=None)
[DEBUG:2023-04-04 23:20:49,474] rhasspydialogue_hermes: Publishing 177 bytes(s) to hermes/dialogueManager/sessionStarted

[DEBUG:2023-04-04 23:20:49,477] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:49,478] rhasspydialogue_hermes: Publishing 62 bytes(s) to hermes/hotword/toggleOff
[DEBUG:2023-04-04 23:20:49,480] rhasspydialogue_hermes: Listening for session himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a
[DEBUG:2023-04-04 23:20:49,484] rhasspydialogue_hermes: -> AsrStartListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='jarvis_raspberry-pi', intent_filter=None)
[DEBUG:2023-04-04 23:20:49,485] rhasspydialogue_hermes: Publishing 249 bytes(s) to hermes/asr/startListening
[DEBUG:2023-04-04 23:20:49,494] rhasspyasr_kaldi_hermes: <- AsrStartListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='jarvis_raspberry-pi', intent_filter=None)
[DEBUG:2023-04-04 23:20:49,494] rhasspyasr_kaldi_hermes: Creating new transcriber session himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a
[DEBUG:2023-04-04 23:20:49,499] rhasspyasr_kaldi.transcribe: Using kaldi at /usr/lib/rhasspy/.venv/lib/kaldi
[DEBUG:2023-04-04 23:20:49,499] rhasspyasr_kaldi_hermes: Starting listening (session_id=himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a)
[DEBUG:2023-04-04 23:20:49,502] rhasspyasr_kaldi.transcribe: Creating FIFO at /tmp/tmp8m5vxlym/chunks.fifo
[DEBUG:2023-04-04 23:20:49,505] rhasspyasr_kaldi.transcribe: ['/usr/lib/rhasspy/.venv/lib/kaldi/online2-cli-nnet3-decode-faster-confidence', '--config=/profiles/de/kaldi/model/online/conf/online.conf', '--max-active=7000', '--lattice-beam=8.0', '--acoustic-scale=1.0', '--beam=24.0', '/profiles/de/kaldi/model/model/final.mdl', '/profiles/de/kaldi/model/graph/HCLG.fst', '/profiles/de/kaldi/model/graph/words.txt', '/tmp/tmp8m5vxlym/chunks.fifo']
[DEBUG:2023-04-04 23:20:49,541] rhasspyasr_kaldi_hermes: Receiving audio
/usr/lib/rhasspy/.venv/lib/kaldi/online2-cli-nnet3-decode-faster-confidence --config=/profiles/de/kaldi/model/online/conf/online.conf --max-active=7000 --lattice-beam=8.0 --acoustic-scale=1.0 --beam=24.0 /profiles/de/kaldi/model/model/final.mdl /profiles/de/kaldi/model/graph/HCLG.fst /profiles/de/kaldi/model/graph/words.txt /tmp/tmp8m5vxlym/chunks.fifo
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:183) Computing derived variables for iVector extractor
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:204) Done.
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanNodes():nnet-nnet.cc:948) Removed 1 orphan nodes.
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanComponents():nnet-nnet.cc:847) Removing 2 orphan components.
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:Collapse():nnet-utils.cc:1488) Added 1 components, removed 2
LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:CompileLooped():nnet-compile-looped.cc:345) Spent 0.0460272 seconds in looped compilation.
[DEBUG:2023-04-04 23:20:50,059] rhasspyasr_kaldi.transcribe: ready
[DEBUG:2023-04-04 23:20:50,059] rhasspyasr_kaldi.transcribe: Decoder started
[DEBUG:2023-04-04 23:20:51,902] rhasspyasr_kaldi_hermes: -> AsrRecordingFinished(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:51,903] rhasspyasr_kaldi_hermes: Publishing 126 bytes(s) to rhasspy/asr/recordingFinished
[DEBUG:2023-04-04 23:20:51,905] rhasspyasr_kaldi.transcribe: Finished stream. Getting transcription.
[DEBUG:2023-04-04 23:20:51,996] rhasspyasr_kaldi.transcribe: 0 nein 1 0 2.41
[DEBUG:2023-04-04 23:20:51,996] rhasspyasr_kaldi_hermes: Transcription result: Transcription(text='nein', likelihood=1.0, transcribe_seconds=1.9363639040002454, wav_seconds=2.432, tokens=[TranscriptionToken(token='nein', start_time=0.0, end_time=2.41, likelihood=1.0)])
[DEBUG:2023-04-04 23:20:52,000] rhasspyasr_kaldi_hermes: -> AsrTextCaptured(text='nein', likelihood=1.0, seconds=1.9363639040002454, site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', wakeword_id=None, asr_tokens=[[AsrToken(value='nein', confidence=1.0, range_start=0, range_end=5, time=AsrTokenTime(start=0.0, end=2.41))]], lang=None)
[DEBUG:2023-04-04 23:20:52,000] rhasspyasr_kaldi_hermes: Publishing 350 bytes(s) to hermes/asr/textCaptured
[DEBUG:2023-04-04 23:20:52,001] rhasspyasr_kaldi_hermes: -> AsrAudioCaptured(35564 byte(s)) to rhasspy/asr/himbipi-satellite-1/himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a/audioCaptured
[DEBUG:2023-04-04 23:20:52,008] rhasspydialogue_hermes: <- AsrTextCaptured(text='nein', likelihood=1.0, seconds=1.9363639040002454, site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', wakeword_id=None, asr_tokens=[[AsrToken(value='nein', confidence=1.0, range_start=0, range_end=5, time=AsrTokenTime(start=0.0, end=2.41))]], lang=None)
[DEBUG:2023-04-04 23:20:52,008] rhasspydialogue_hermes: Playing sound /profiles/de/wav/end_of_input.wav
[DEBUG:2023-04-04 23:20:52,010] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,011] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/hotword/toggleOff
[DEBUG:2023-04-04 23:20:52,012] rhasspydialogue_hermes: -> AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,012] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/asr/toggleOff
[DEBUG:2023-04-04 23:20:52,013] rhasspydialogue_hermes: -> AudioPlayBytes(95312 byte(s)) to hermes/audioServer/himbipi-satellite-1/playBytes/aa3c96ec-b0b9-4d93-9731-7596311d1078
[DEBUG:2023-04-04 23:20:52,013] rhasspydialogue_hermes: Waiting for playFinished (id=aa3c96ec-b0b9-4d93-9731-7596311d1078, timeout=2.4102721088435373)
[DEBUG:2023-04-04 23:20:52,018] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,018] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.PLAY_AUDIO)
[DEBUG:2023-04-04 23:20:52,584] rhasspydialogue_hermes: <- AudioPlayFinished(id='aa3c96ec-b0b9-4d93-9731-7596311d1078', session_id='aa3c96ec-b0b9-4d93-9731-7596311d1078')
[DEBUG:2023-04-04 23:20:52,583] rhasspytts_cli_hermes: <- AudioPlayFinished(id='aa3c96ec-b0b9-4d93-9731-7596311d1078', session_id='aa3c96ec-b0b9-4d93-9731-7596311d1078')
[DEBUG:2023-04-04 23:20:52,585] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,586] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/hotword/toggleOn
[DEBUG:2023-04-04 23:20:52,588] rhasspydialogue_hermes: -> AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,588] rhasspydialogue_hermes: Publishing 56 bytes(s) to hermes/asr/toggleOn
[DEBUG:2023-04-04 23:20:52,589] rhasspydialogue_hermes: Received text: nein
[DEBUG:2023-04-04 23:20:52,590] rhasspydialogue_hermes: -> AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,591] rhasspydialogue_hermes: Publishing 126 bytes(s) to hermes/asr/stopListening
[DEBUG:2023-04-04 23:20:52,593] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:52,593] rhasspydialogue_hermes: Publishing 62 bytes(s) to hermes/hotword/toggleOn
[DEBUG:2023-04-04 23:20:52,595] rhasspydialogue_hermes: -> NluQuery(input='nein', site_id='himbipi-satellite-1', id=None, intent_filter=None, session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', wakeword_id='jarvis_raspberry-pi', lang=None, custom_data='jarvis_raspberry-pi', asr_confidence=1.0, custom_entities=None)
[DEBUG:2023-04-04 23:20:52,595] rhasspydialogue_hermes: Publishing 311 bytes(s) to hermes/nlu/query
[DEBUG:2023-04-04 23:20:52,597] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
[DEBUG:2023-04-04 23:20:52,598] rhasspyasr_kaldi_hermes: Enabled
[DEBUG:2023-04-04 23:20:52,601] rhasspyasr_kaldi_hermes: <- AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,601] rhasspyasr_kaldi_hermes: Stopping listening (session_id=himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a)
[DEBUG:2023-04-04 23:20:52,601] rhasspynlu_hermes: <- NluQuery(input='nein', site_id='himbipi-satellite-1', id=None, intent_filter=None, session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', wakeword_id='jarvis_raspberry-pi', lang=None, custom_data='jarvis_raspberry-pi', asr_confidence=1.0, custom_entities=None)
[DEBUG:2023-04-04 23:20:52,608] rhasspynlu_hermes: -> NluIntentParsed(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,608] rhasspynlu_hermes: Publishing 224 bytes(s) to hermes/nlu/intentParsed
[DEBUG:2023-04-04 23:20:52,611] rhasspynlu_hermes: -> NluIntent(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', custom_data='jarvis_raspberry-pi', asr_tokens=[[AsrToken(value='Nein', confidence=1.0, range_start=0, range_end=4, time=None)]], asr_confidence=1.0, raw_input='nein', wakeword_id='jarvis_raspberry-pi', lang=None)
[DEBUG:2023-04-04 23:20:52,612] rhasspynlu_hermes: Publishing 455 bytes(s) to hermes/intent/No
[DEBUG:2023-04-04 23:20:52,660] rhasspyhomeassistant_hermes: <- NluIntent(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', custom_data='jarvis_raspberry-pi', asr_tokens=[[AsrToken(value='Nein', confidence=1.0, range_start=0, range_end=4, time=None)]], asr_confidence=1.0, raw_input='nein', wakeword_id='jarvis_raspberry-pi', lang=None)
[DEBUG:2023-04-04 23:20:52,662] rhasspyserver_hermes: Sent 406 char(s) to websocket
[DEBUG:2023-04-04 23:20:52,664] rhasspyhomeassistant_hermes: http://192.168.178.21:8123/api/intent/handle
[DEBUG:2023-04-04 23:20:52,664] rhasspydialogue_hermes: <- NluIntent(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', custom_data='jarvis_raspberry-pi', asr_tokens=[[AsrToken(value='Nein', confidence=1.0, range_start=0, range_end=4, time=None)]], asr_confidence=1.0, raw_input='nein', wakeword_id='jarvis_raspberry-pi', lang=None)
[DEBUG:2023-04-04 23:20:52,665] rhasspydialogue_hermes: Recognized NluIntent(input='Nein', intent=Intent(intent_name='No', confidence_score=1.0), site_id='himbipi-satellite-1', id=None, slots=[], session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', custom_data='jarvis_raspberry-pi', asr_tokens=[[AsrToken(value='Nein', confidence=1.0, range_start=0, range_end=4, time=None)]], asr_confidence=1.0, raw_input='nein', wakeword_id='jarvis_raspberry-pi', lang=None)
[ERROR:2023-04-04 23:20:52,693] rhasspyhomeassistant_hermes: handle_home_assistant_intent
Traceback (most recent call last):
  File "/usr/lib/rhasspy/rhasspy-homeassistant-hermes/rhasspyhomeassistant_hermes/__init__.py", line 173, in handle_home_assistant_intent
    response.raise_for_status()
  File "/usr/lib/rhasspy/.venv/lib/python3.7/site-packages/aiohttp/client_reqrep.py", line 1005, in raise_for_status
    headers=self.headers,
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://192.168.178.21:8123/api/intent/handle')
[ERROR:2023-04-04 23:20:52,693] rhasspyhomeassistant_hermes: handle_intent
Traceback (most recent call last):
  File "/usr/lib/rhasspy/rhasspy-homeassistant-hermes/rhasspyhomeassistant_hermes/__init__.py", line 88, in handle_intent
    assert response_dict, f"No response from {self.url}"
AssertionError: No response from http://192.168.178.21:8123/
[DEBUG:2023-04-04 23:20:52,701] rhasspydialogue_hermes: <- DialogueEndSession(session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', text='Okay, ich breche ab', custom_data=None)
[DEBUG:2023-04-04 23:20:52,702] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,702] rhasspydialogue_hermes: Publishing 53 bytes(s) to hermes/hotword/toggleOff
[DEBUG:2023-04-04 23:20:52,704] rhasspydialogue_hermes: -> AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,705] rhasspydialogue_hermes: Publishing 53 bytes(s) to hermes/asr/toggleOff
[DEBUG:2023-04-04 23:20:52,705] rhasspydialogue_hermes: Say: Okay, ich breche ab
[DEBUG:2023-04-04 23:20:52,708] rhasspydialogue_hermes: -> TtsSay(text='Okay, ich breche ab', site_id='himbipi-satellite-1', lang=None, id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', volume=None)
[DEBUG:2023-04-04 23:20:52,708] rhasspydialogue_hermes: Publishing 233 bytes(s) to hermes/tts/say
[DEBUG:2023-04-04 23:20:52,708] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='himbipi-satellite-1', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,709] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.TTS_SAY)
[DEBUG:2023-04-04 23:20:52,710] rhasspydialogue_hermes: Waiting for sayFinished (id=db899083-47b4-457e-8f4e-7f95ab53b439, timeout=10.0)
[DEBUG:2023-04-04 23:20:52,713] rhasspytts_cli_hermes: <- TtsSay(text='Okay, ich breche ab', site_id='himbipi-satellite-1', lang=None, id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', volume=None)
[DEBUG:2023-04-04 23:20:52,715] rhasspytts_cli_hermes: ['nanotts', '-v', 'de-DE', '-o', '/tmp/tmpdnapm49i.wav']
Using Lingware directory: /usr/lib/rhasspy/.venv/lib/nanotts/pico/lang
read: 19 bytes from stdin
using lang: de-DE
wrote "/tmp/tmpdnapm49i.wav" (52908 bytes)
[DEBUG:2023-04-04 23:20:52,784] rhasspytts_cli_hermes: Got 52908 byte(s) of WAV data
[DEBUG:2023-04-04 23:20:52,784] rhasspytts_cli_hermes: -> AudioPlayBytes(52908 byte(s)) to hermes/audioServer/himbipi-satellite-1/playBytes/db899083-47b4-457e-8f4e-7f95ab53b439
[DEBUG:2023-04-04 23:20:52,787] rhasspytts_cli_hermes: Waiting for play finished (timeout=1.902)
[DEBUG:2023-04-04 23:20:52,864] rhasspydialogue_hermes: <- AudioPlayFinished(id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='db899083-47b4-457e-8f4e-7f95ab53b439')
[DEBUG:2023-04-04 23:20:52,865] rhasspytts_cli_hermes: <- AudioPlayFinished(id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='db899083-47b4-457e-8f4e-7f95ab53b439')
[DEBUG:2023-04-04 23:20:52,867] rhasspytts_cli_hermes: -> TtsSayFinished(site_id='himbipi-satellite-1', id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,867] rhasspytts_cli_hermes: Publishing 172 bytes(s) to hermes/tts/sayFinished
[DEBUG:2023-04-04 23:20:52,905] rhasspydialogue_hermes: <- TtsSayFinished(site_id='himbipi-satellite-1', id='db899083-47b4-457e-8f4e-7f95ab53b439', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,906] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,906] rhasspydialogue_hermes: Publishing 53 bytes(s) to hermes/hotword/toggleOn
[DEBUG:2023-04-04 23:20:52,908] rhasspydialogue_hermes: -> AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,908] rhasspydialogue_hermes: Publishing 53 bytes(s) to hermes/asr/toggleOn
[DEBUG:2023-04-04 23:20:52,909] rhasspydialogue_hermes: Session ended nominally: himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a
[DEBUG:2023-04-04 23:20:52,911] rhasspydialogue_hermes: -> AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,911] rhasspydialogue_hermes: Publishing 126 bytes(s) to hermes/asr/stopListening
[DEBUG:2023-04-04 23:20:52,914] rhasspydialogue_hermes: -> DialogueSessionEnded(termination=DialogueSessionTermination(reason=<DialogueSessionTerminationReason.NOMINAL: 'nominal'>), session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a', site_id='himbipi-satellite-1', custom_data='jarvis_raspberry-pi')
[DEBUG:2023-04-04 23:20:52,915] rhasspydialogue_hermes: Publishing 201 bytes(s) to hermes/dialogueManager/sessionEnded
[DEBUG:2023-04-04 23:20:52,916] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='himbipi-satellite-1', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
[DEBUG:2023-04-04 23:20:52,916] rhasspydialogue_hermes: Publishing 62 bytes(s) to hermes/hotword/toggleOn
[DEBUG:2023-04-04 23:20:52,915] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='himbipi-satellite-1', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
[DEBUG:2023-04-04 23:20:52,915] rhasspyasr_kaldi_hermes: Enabled
[DEBUG:2023-04-04 23:20:52,918] rhasspyasr_kaldi_hermes: <- AsrStopListening(site_id='himbipi-satellite-1', session_id='himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a')
[DEBUG:2023-04-04 23:20:52,919] rhasspyasr_kaldi_hermes: Stopping listening (session_id=himbipi-satellite-1-jarvis_raspberry-pi-7892b6c8-2b61-49c9-9762-d83a72bfbb5a)

What am I doing wrong?

[Mondmonarch]

satellite config:

{
    "home_assistant": {
        "access_token": "secret",
        "url": "http://192.168.178.21:8123/"
    },
    "intent": {
        "system": "hermes"
    },
    "microphone": {
        "arecord": {
            "device": "hw:CARD=MATRIXIOSOUND,DEV=0",
            "udp_audio_host": "localhost",
            "udp_audio_port": "12202"
        },
        "system": "arecord"
    },
    "mqtt": {
        "enabled": "true",
        "host": "192.168.178.22",
        "password": "secret",
        "site_id": "himbipi-satellite-1",
        "username": "user"
    },
    "sounds": {
        "aplay": {
            "device": "hw:CARD=Device,DEV=0",
            "volume": "1"
        },
        "command": {
            "play_program": "/home/pi/.config/rhasspy/profiles/de/copy-audio.sh"
        },
        "error": "/home/pi/.config/rhasspy/profiles/de/sounds/error.wav",
        "recorded": "/home/pi/.config/rhasspy/profiles/de/sounds/end_of_input.wav",
        "system": "aplay",
        "wake": "/home/pi/.config/rhasspy/profiles/de/sounds/start_of_input.wav"
    },
    "speech_to_text": {
        "system": "hermes"
    },
    "text_to_speech": {
        "system": "hermes"
    },
    "wake": {
        "porcupine": {
            "keyword_path": "jarvis_raspberry-pi.ppn",
            "sensitivity": "0.5",
            "udp_audio": "localhost:12202:himbipi-satellite-1"
        },
        "system": "porcupine"
    }
}

base config:

{
    "dialogue": {
        "satellite_site_ids": "himbipi-satellite-1",
        "system": "rhasspy"
    },
    "handle": {
        "satellite_site_ids": "himbipi-satellite-1",
        "system": "hass"
    },
    "home_assistant": {
        "access_token": "secret",
        "url": "http://192.168.178.21:8123/"
    },
    "intent": {
        "satellite_site_ids": "himbipi-satellite-1",
        "system": "fsticuffs"
    },
    "mqtt": {
        "enabled": "true",
        "host": "192.168.178.22",
        "password": "secret",
        "username": "user"
    },
    "sounds": {
        "error": "${RHASSPY_PROFILE_DIR}/wav/error.wav",
        "recorded": "${RHASSPY_PROFILE_DIR}/wav/end_of_input.wav",
        "wake": "${RHASSPY_PROFILE_DIR}/wav/start_of_input.wav"
    },
    "speech_to_text": {
        "satellite_site_ids": "himbipi-satellite-1",
        "system": "kaldi"
    },
    "text_to_speech": {
        "satellite_site_ids": "himbipi-satellite-1",
        "system": "nanotts"
    }
}

[Mondmonarch]

WORKAROUND:
I went with http satellite instead of mqtt satellite.
I also needed to set TTS on the satellite:

Satellite IDs Break Audio

I set TTS to be performed on the satellite, and everything works. The documentation suggested TTS should be done on the base, but I can’t figure out how to relay that TTS data back to the satellite. As I only have one satellite at the moment it probably isn’t an issue, but I can see this possibly being a problem with multiple satellites, as Home Assistant would need some way to know which satellite sent the command. Is this possible with intents? I recall something about the ability to include …