Silence after wake word. Intent not recognized

Hi,

I am using rhasspy with recommended setting and it works quite well very good job to developers :slight_smile:

But there is a little thing I don’t understand. For example when my wake word is recognized I try to be quiet for testing how it reacts and after few seconds I was expected a timeout but instead I have a IntentNotRecognized.

Is it the normal behaviour ?

There is another problem sometimes the system detects the wrong intent which is far far away from the expected one !

I am using arecord, Porcupine, Kaldi, Fsticuffs, NanoTTS, aplay and rhasspy.

Best regards,

Eric

So you said the wakeword to let Rhasspy know that you are going to give it a command 
 and then failed to give it any command ?

  • Porcupine recognised your wakeword, and started Rhasspy recording audio until it detected the length of silence which normally follows a command.
  • Rhasspy called Kaldi to translate the recorded audio to text.
  • Rhasspy called Fsticuffs to work out your intent (command) from the text.
  • and Rhasspy informed you that it could not recognise your intent.

Yes, this is normal behaviour, because they are separate phases. Looking good, Eric :slight_smile:

Not exactly what i mean.

Let’s say we say the wake word (recognized by system) but after we say nothing.

I thought the system will trigger a timeout but the system is triggering a intent not recognized.

Normal behaviour or not ?

Obviously, Rhasspy hears noises and interprets them as speech. It could be background noise or the local echo of the “Recorded WAV sound”. Deactivate the sounds for testing.

Internally the Sound Recording is timing out after x second of silence, but it can’t know at that point whether what it may (or may not) have recorded was a valid command (intent).

As jens-schiffke mentioned, background noise, differences in voices or pronunciation can also affect the wakeword recognition or speech to text and consequently the intent recognition. Voice processing is a very imprecise art.

Eric, you say you thought the system will trigger a timeout 
 but what would that do ?

  • Should Rhasspy go back to listening until it hears a valid intent ? What if hearing the wakeword itself was a false positive ? Should it listen indefinitely ?

  • Should Rhasspy give a different response ? the equivalent of us humans responding “Well, what the fork do you want?” rather than Rhasspy’s “I didn’t understand that” ?
    I’m not a developer (so don’t quote me) but this might be do-able. Rhasspy could differentiate between Kaldi returning no text (ie it didn’t hear recognisable words), and Fsticuffs determining that the text was not a valid intent.

Hi,

Thank you for your answers. The IntentNotRecognized it’s not very important for now.
But I think i have an issue with silence settings because often after the wakeword is recognized I am speaking for a command and the system return IntentNotRecognized before the end of my command (not a very long command).

Edit : I don’t understand sometimes the speech to text is perfect (the one written on the home) but the intent is not recognized ! Very strange. If I click on the “recognize” button it is recognized !

Hi,

Can someone help me because sometimes the system detects intent just with one word.
I can reproduce false positive every time.

In my sentence I have this intent :
[DeviceOff]
arrĂȘt $Devices{device} (:){point:OnOff} (:){action:off}

point and action are just extras

$Devices references to slot program that returns this :
(Cuve b un):(Device1)
(Cuve b deux):(Device2)
(Cuve [numéro] 56):(Device3)
(Cuve [numéro] 57):(Device4)

So now I start the system with wake word “tournesol” with porcupine : works great

Then I say just “deux” and it always triggers the intent [DeviceOff] with text “arret Cuve b deux”.
I don’t understant why because the word “arret” is missing.

I attach the logs in next posts from wake word to recognized intent if you can see why my intent is triggered


Thank you in advance.

Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,098] rhasspywake_porcupine_hermes: -> HotwordDetected(model_id='/home/pi/.config/rhasspy/profiles/fr/porcupine/tournesol_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.45, site_id='rpi', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,099] rhasspywake_porcupine_hermes: Publishing 262 bytes(s) to hermes/hotword/tournesol_raspberry-pi/detected
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,104] rhasspydialogue_hermes: <- HotwordDetected(model_id='/home/pi/.config/rhasspy/profiles/fr/porcupine/tournesol_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.45, site_id='rpi', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,104] rhasspyserver_hermes: <- HotwordDetected(model_id='/home/pi/.config/rhasspy/profiles/fr/porcupine/tournesol_raspberry-pi.ppn', model_version='', model_type='personal', current_sensitivity=0.45, site_id='rpi', session_id=None, send_audio_captured=None, lang=None, custom_entities=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,105] rhasspydialogue_hermes: Playing sound /usr/lib/rhasspy/etc/wav/beep_hi.wav
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,107] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,108] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/hotword/toggleOff
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,110] rhasspydialogue_hermes: -> AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,110] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/asr/toggleOff
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,112] rhasspydialogue_hermes: -> AudioPlayBytes(83948 byte(s)) to hermes/audioServer/rpi/playBytes/f05fa189-7386-44ee-8507-d79085057e39
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,114] rhasspydialogue_hermes: Waiting for playFinished (id=f05fa189-7386-44ee-8507-d79085057e39, timeout=1.2012925170068027)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,112] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,113] rhasspywake_porcupine_hermes: Disabled
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,116] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,116] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.PLAY_AUDIO)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,120] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(83948 byte(s))
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,121] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'plughw:CARD=ArrayUAC10,DEV=0']
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,125] rhasspyspeakers_cli_hermes: Final volume is 0.5 (master=0.5)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,686] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='f05fa189-7386-44ee-8507-d79085057e39', session_id='f05fa189-7386-44ee-8507-d79085057e39')
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,687] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/rpi/playFinished
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,691] rhasspydialogue_hermes: <- AudioPlayFinished(id='f05fa189-7386-44ee-8507-d79085057e39', session_id='f05fa189-7386-44ee-8507-d79085057e39')
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,691] rhasspytts_cli_hermes: <- AudioPlayFinished(id='f05fa189-7386-44ee-8507-d79085057e39', session_id='f05fa189-7386-44ee-8507-d79085057e39')
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,693] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,694] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/hotword/toggleOn
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,696] rhasspydialogue_hermes: -> AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,697] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/asr/toggleOn
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,697] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,698] rhasspywake_porcupine_hermes: Enabled
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,698] rhasspydialogue_hermes: Starting new session (id=rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,701] rhasspydialogue_hermes: -> DialogueSessionStarted(session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', site_id='rpi', custom_data='tournesol_raspberry-pi', lang=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,702] rhasspydialogue_hermes: Publishing 151 bytes(s) to hermes/dialogueManager/sessionStarted
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,703] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,704] rhasspyasr_kaldi_hermes: Enabled
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,705] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,705] rhasspydialogue_hermes: Publishing 46 bytes(s) to hermes/hotword/toggleOff
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,706] rhasspydialogue_hermes: Listening for session rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,708] rhasspydialogue_hermes: -> AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='tournesol_raspberry-pi', intent_filter=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,709] rhasspydialogue_hermes: Publishing 223 bytes(s) to hermes/asr/startListening
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,713] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,714] rhasspywake_porcupine_hermes: Disabled
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,718] rhasspyasr_kaldi_hermes: <- AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='tournesol_raspberry-pi', intent_filter=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,720] rhasspyasr_kaldi_hermes: Creating new transcriber session rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,722] rhasspyasr_kaldi.transcribe: Using kaldi at /usr/lib/rhasspy/lib/kaldi
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,723] rhasspyasr_kaldi_hermes: Starting listening (session_id=rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,720] rhasspymicrophone_cli_hermes: <- AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id='tournesol_raspberry-pi', intent_filter=None)
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,721] rhasspymicrophone_cli_hermes: Disable UDP output
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,727] rhasspyasr_kaldi_hermes: Receiving audio
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,731] rhasspyasr_kaldi.transcribe: Creating FIFO at /tmp/tmpwscr2iku/chunks.fifo
Sep 28 10:31:21 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:21,734] rhasspyasr_kaldi.transcribe: ['/usr/lib/rhasspy/lib/kaldi/online2-cli-nnet3-decode-faster-confidence', '--config=/home/pi/.config/rhasspy/profiles/fr/kaldi/model/online/conf/online.conf', '--max-active=7000', '--lattice-beam=8.0', '--acoustic-scale=1.0', '--beam=24.0', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/model/final.mdl', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/HCLG.fst', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/words.txt', '/tmp/tmpwscr2iku/chunks.fifo']
Sep 28 10:31:21 raspberrypi rhasspy[573]: /usr/lib/rhasspy/lib/kaldi/online2-cli-nnet3-decode-faster-confidence --config=/home/pi/.config/rhasspy/profiles/fr/kaldi/model/online/conf/online.conf --max-active=7000 --lattice-beam=8.0 --acoustic-scale=1.0 --beam=24.0 /home/pi/.config/rhasspy/profiles/fr/kaldi/model/model/final.mdl /home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/HCLG.fst /home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/words.txt /tmp/tmpwscr2iku/chunks.fifo
Sep 28 10:31:21 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:183) Computing derived variables for iVector extractor
Sep 28 10:31:22 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:204) Done.
Sep 28 10:31:22 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanNodes():nnet-nnet.cc:948) Removed 1 orphan nodes.
Sep 28 10:31:22 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanComponents():nnet-nnet.cc:847) Removing 2 orphan components.
Sep 28 10:31:22 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:Collapse():nnet-utils.cc:1488) Added 1 components, removed 2
Sep 28 10:31:22 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:CompileLooped():nnet-compile-looped.cc:345) Spent 0.0712101 seconds in looped compilation.
Sep 28 10:31:22 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:22,538] rhasspyasr_kaldi.transcribe: ready
Sep 28 10:31:22 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:22,538] rhasspyasr_kaldi.transcribe: Decoder started
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,719] rhasspyasr_kaldi_hermes: -> AsrRecordingFinished(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,720] rhasspyasr_kaldi_hermes: Publishing 97 bytes(s) to rhasspy/asr/recordingFinished
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,722] rhasspyasr_kaldi.transcribe: Finished stream. Getting transcription.
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,984] rhasspyasr_kaldi.transcribe: 0.434864 arrĂȘt 0.574746 0 0 cuve 1 0 0 b 1 0 0 deux 1 0.00427309 3.04347
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,985] rhasspyasr_kaldi_hermes: Transcription result: Transcription(text='arrĂȘt cuve b deux', likelihood=0.5651360000000001, transcribe_seconds=2.4457326749979984, wav_seconds=3.072, tokens=[TranscriptionToken(token='arrĂȘt', start_time=0.0, end_time=0.0, likelihood=0.574746), TranscriptionToken(token='cuve', start_time=0.0, end_time=0.0, likelihood=1.0), TranscriptionToken(token='b', start_time=0.0, end_time=0.0, likelihood=1.0), TranscriptionToken(token='deux', start_time=0.00427309, end_time=3.04347, likelihood=1.0)])
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,995] rhasspyasr_kaldi_hermes: -> AsrTextCaptured(text='arrĂȘt cuve b deux', likelihood=0.5651360000000001, seconds=2.4457326749979984, site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', wakeword_id=None, asr_tokens=[[AsrToken(value='arrĂȘt', confidence=0.574746, range_start=0, range_end=6, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='cuve', confidence=1.0, range_start=6, range_end=11, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='b', confidence=1.0, range_start=11, range_end=13, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='deux', confidence=1.0, range_start=13, range_end=18, time=AsrTokenTime(start=0.00427309, end=3.04347))]], lang=None)
Sep 28 10:31:24 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:24,996] rhasspyasr_kaldi_hermes: Publishing 685 bytes(s) to hermes/asr/textCaptured
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,017] rhasspyasr_kaldi_hermes: -> AsrAudioCaptured(73004 byte(s)) to rhasspy/asr/rpi/rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262/audioCaptured
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,024] rhasspydialogue_hermes: <- AsrTextCaptured(text='arrĂȘt cuve b deux', likelihood=0.5651360000000001, seconds=2.4457326749979984, site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', wakeword_id=None, asr_tokens=[[AsrToken(value='arrĂȘt', confidence=0.574746, range_start=0, range_end=6, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='cuve', confidence=1.0, range_start=6, range_end=11, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='b', confidence=1.0, range_start=11, range_end=13, time=AsrTokenTime(start=0.0, end=0.0)), AsrToken(value='deux', confidence=1.0, range_start=13, range_end=18, time=AsrTokenTime(start=0.00427309, end=3.04347))]], lang=None)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,025] rhasspydialogue_hermes: Playing sound /usr/lib/rhasspy/etc/wav/beep_lo.wav
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,030] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,031] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/hotword/toggleOff
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,034] rhasspydialogue_hermes: -> AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,035] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/asr/toggleOff
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,037] rhasspydialogue_hermes: -> AudioPlayBytes(119908 byte(s)) to hermes/audioServer/rpi/playBytes/89468683-98c4-422d-92c0-0ce24323fd42
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,039] rhasspydialogue_hermes: Waiting for playFinished (id=89468683-98c4-422d-92c0-0ce24323fd42, timeout=1.6090022675736961)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,060] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,057] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,058] rhasspywake_porcupine_hermes: Disabled
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,060] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(119908 byte(s))
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,060] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.PLAY_AUDIO)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,074] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'plughw:CARD=ArrayUAC10,DEV=0']
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,080] rhasspyspeakers_cli_hermes: Final volume is 0.5 (master=0.5)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,901] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='89468683-98c4-422d-92c0-0ce24323fd42', session_id='89468683-98c4-422d-92c0-0ce24323fd42')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,901] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/rpi/playFinished
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,906] rhasspydialogue_hermes: <- AudioPlayFinished(id='89468683-98c4-422d-92c0-0ce24323fd42', session_id='89468683-98c4-422d-92c0-0ce24323fd42')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,906] rhasspytts_cli_hermes: <- AudioPlayFinished(id='89468683-98c4-422d-92c0-0ce24323fd42', session_id='89468683-98c4-422d-92c0-0ce24323fd42')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,909] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,909] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/hotword/toggleOn
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,911] rhasspydialogue_hermes: -> AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,912] rhasspydialogue_hermes: Publishing 40 bytes(s) to hermes/asr/toggleOn
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,914] rhasspydialogue_hermes: Received text: arrĂȘt cuve b deux
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,917] rhasspydialogue_hermes: -> AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,919] rhasspydialogue_hermes: Publishing 97 bytes(s) to hermes/asr/stopListening
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,922] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,924] rhasspydialogue_hermes: Publishing 46 bytes(s) to hermes/hotword/toggleOn
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,923] rhasspymicrophone_cli_hermes: <- AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,924] rhasspymicrophone_cli_hermes: Enable UDP output
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,927] rhasspydialogue_hermes: -> NluQuery(input='arrĂȘt cuve b deux', site_id='rpi', id=None, intent_filter=None, session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', wakeword_id='tournesol_raspberry-pi', lang=None, custom_data='tournesol_raspberry-pi', asr_confidence=0.5651360000000001, custom_entities=None)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,930] rhasspydialogue_hermes: Publishing 316 bytes(s) to hermes/nlu/query
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,937] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,937] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.PLAY_AUDIO: 'playAudio'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,938] rhasspyasr_kaldi_hermes: Enabled
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,940] rhasspywake_porcupine_hermes: Still disabled: {<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>}
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,941] rhasspyasr_kaldi_hermes: <- AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,942] rhasspyasr_kaldi_hermes: Stopping listening (session_id=rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,947] rhasspynlu_hermes: <- NluQuery(input='arrĂȘt cuve b deux', site_id='rpi', id=None, intent_filter=None, session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', wakeword_id='tournesol_raspberry-pi', lang=None, custom_data='tournesol_raspberry-pi', asr_confidence=0.5651360000000001, custom_entities=None)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,949] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,953] rhasspywake_porcupine_hermes: Enabled
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,970] rhasspywake_porcupine_hermes: Receiving audio rpi
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,971] rhasspynlu_hermes: -> NluIntentParsed(input='arrĂȘt Device2 OnOff off', intent=Intent(intent_name='DeviceOff', confidence_score=1.0), site_id='rpi', id=None, slots=[Slot(entity='Devices', value={'kind': 'Unknown', 'value': 'Device2'}, slot_name='device', raw_value='cuve b deux', confidence=1.0, range=SlotRange(start=6, end=13, raw_start=6, raw_end=17)), Slot(entity='point', value={'kind': 'Unknown', 'value': 'OnOff'}, slot_name='point', raw_value='', confidence=1.0, range=SlotRange(start=14, end=19, raw_start=18, raw_end=17)), Slot(entity='action', value={'kind': 'Unknown', 'value': 'off'}, slot_name='action', raw_value='', confidence=1.0, range=SlotRange(start=20, end=23, raw_start=18, raw_end=17))], session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,971] rhasspynlu_hermes: Publishing 803 bytes(s) to hermes/nlu/intentParsed
Sep 28 10:31:25 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,998] rhasspynlu_hermes: -> NluIntent(input='arrĂȘt Device2 OnOff off', intent=Intent(intent_name='DeviceOff', confidence_score=1.0), site_id='rpi', id=None, slots=[Slot(entity='Devices', value={'kind': 'Unknown', 'value': 'Device2'}, slot_name='device', raw_value='cuve b deux', confidence=1.0, range=SlotRange(start=6, end=13, raw_start=6, raw_end=17)), Slot(entity='point', value={'kind': 'Unknown', 'value': 'OnOff'}, slot_name='point', raw_value='', confidence=1.0, range=SlotRange(start=14, end=19, raw_start=18, raw_end=17)), Slot(entity='action', value={'kind': 'Unknown', 'value': 'off'}, slot_name='action', raw_value='', confidence=1.0, range=SlotRange(start=20, end=23, raw_start=18, raw_end=17))], session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', custom_data='tournesol_raspberry-pi', asr_tokens=[[AsrToken(value='arrĂȘt', confidence=1.0, range_start=0, range_end=5, time=None), AsrToken(value='Device2', confidence=1.0, range_start=6, range_end=13, time=None), AsrToken(value='OnOff', confidence=1.0, range_start=14, range_end=19, time=None), AsrToken(value='off', confidence=1.0, range_start=20, range_end=23, time=None)]], asr_confidence=0.5651360000000001, raw_input='arrĂȘt cuve b deux', wakeword_id='tournesol_raspberry-pi', lang=None)
Sep 28 10:31:26 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:25,999] rhasspynlu_hermes: Publishing 1329 bytes(s) to hermes/intent/DeviceOff
Sep 28 10:31:26 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:26,025] rhasspyserver_hermes: <- NluIntent(input='arrĂȘt Device2 OnOff off', intent=Intent(intent_name='DeviceOff', confidence_score=1.0), site_id='rpi', id=None, slots=[Slot(entity='Devices', value={'kind': 'Unknown', 'value': 'Device2'}, slot_name='device', raw_value='cuve b deux', confidence=1.0, range=SlotRange(start=6, end=13, raw_start=6, raw_end=17)), Slot(entity='point', value={'kind': 'Unknown', 'value': 'OnOff'}, slot_name='point', raw_value='', confidence=1.0, range=SlotRange(start=14, end=19, raw_start=18, raw_end=17)), Slot(entity='action', value={'kind': 'Unknown', 'value': 'off'}, slot_name='action', raw_value='', confidence=1.0, range=SlotRange(start=20, end=23, raw_start=18, raw_end=17))], session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', custom_data='tournesol_raspberry-pi', asr_tokens=[[AsrToken(value='arrĂȘt', confidence=1.0, range_start=0, range_end=5, time=None), AsrToken(value='Device2', confidence=1.0, range_start=6, range_end=13, time=None), AsrToken(value='OnOff', confidence=1.0, range_start=14, range_end=19, time=None), AsrToken(value='off', confidence=1.0, range_start=20, range_end=23, time=None)]], asr_confidence=0.5651360000000001, raw_input='arrĂȘt cuve b deux', wakeword_id='tournesol_raspberry-pi', lang=None)
Sep 28 10:31:26 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:26,030] rhasspydialogue_hermes: <- NluIntent(input='arrĂȘt Device2 OnOff off', intent=Intent(intent_name='DeviceOff', confidence_score=1.0), site_id='rpi', id=None, slots=[Slot(entity='Devices', value={'kind': 'Unknown', 'value': 'Device2'}, slot_name='device', raw_value='cuve b deux', confidence=1.0, range=SlotRange(start=6, end=13, raw_start=6, raw_end=17)), Slot(entity='point', value={'kind': 'Unknown', 'value': 'OnOff'}, slot_name='point', raw_value='', confidence=1.0, range=SlotRange(start=14, end=19, raw_start=18, raw_end=17)), Slot(entity='action', value={'kind': 'Unknown', 'value': 'off'}, slot_name='action', raw_value='', confidence=1.0, range=SlotRange(start=20, end=23, raw_start=18, raw_end=17))], session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', custom_data='tournesol_raspberry-pi', asr_tokens=[[AsrToken(value='arrĂȘt', confidence=1.0, range_start=0, range_end=5, time=None), AsrToken(value='Device2', confidence=1.0, range_start=6, range_end=13, time=None), AsrToken(value='OnOff', confidence=1.0, range_start=14, range_end=19, time=None), AsrToken(value='off', confidence=1.0, range_start=20, range_end=23, time=None)]], asr_confidence=0.5651360000000001, raw_input='arrĂȘt cuve b deux', wakeword_id='tournesol_raspberry-pi', lang=None)
Sep 28 10:31:26 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:26,033] rhasspydialogue_hermes: Recognized NluIntent(input='arrĂȘt Device2 OnOff off', intent=Intent(intent_name='DeviceOff', confidence_score=1.0), site_id='rpi', id=None, slots=[Slot(entity='Devices', value={'kind': 'Unknown', 'value': 'Device2'}, slot_name='device', raw_value='cuve b deux', confidence=1.0, range=SlotRange(start=6, end=13, raw_start=6, raw_end=17)), Slot(entity='point', value={'kind': 'Unknown', 'value': 'OnOff'}, slot_name='point', raw_value='', confidence=1.0, range=SlotRange(start=14, end=19, raw_start=18, raw_end=17)), Slot(entity='action', value={'kind': 'Unknown', 'value': 'off'}, slot_name='action', raw_value='', confidence=1.0, range=SlotRange(start=20, end=23, raw_start=18, raw_end=17))], session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', custom_data='tournesol_raspberry-pi', asr_tokens=[[AsrToken(value='arrĂȘt', confidence=1.0, range_start=0, range_end=5, time=None), AsrToken(value='Device2', confidence=1.0, range_start=6, range_end=13, time=None), AsrToken(value='OnOff', confidence=1.0, range_start=14, range_end=19, time=None), AsrToken(value='off', confidence=1.0, range_start=20, range_end=23, time=None)]], asr_confidence=0.5651360000000001, raw_input='arrĂȘt cuve b deux', wakeword_id='tournesol_raspberry-pi', lang=None)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,049] rhasspydialogue_hermes: <- DialogueContinueSession(session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', custom_data=None, text='arrĂȘt cuve b deux', intent_filter=None, send_intent_not_recognized=False, slot=None, lang=None)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,052] rhasspydialogue_hermes: Continuing session rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262 (step=1)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,055] rhasspydialogue_hermes: -> AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,055] rhasspydialogue_hermes: Publishing 97 bytes(s) to hermes/asr/stopListening
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,058] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,058] rhasspydialogue_hermes: Publishing 46 bytes(s) to hermes/hotword/toggleOff
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,059] rhasspyasr_kaldi_hermes: <- AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,059] rhasspymicrophone_cli_hermes: <- AsrStopListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,059] rhasspymicrophone_cli_hermes: Enable UDP output
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,059] rhasspyasr_kaldi_hermes: Stopping listening (session_id=rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,061] rhasspydialogue_hermes: -> HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,062] rhasspydialogue_hermes: Publishing 37 bytes(s) to hermes/hotword/toggleOff
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,064] rhasspydialogue_hermes: -> AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,065] rhasspydialogue_hermes: Publishing 37 bytes(s) to hermes/asr/toggleOff
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,066] rhasspydialogue_hermes: Say: arrĂȘt cuve b deux
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,068] rhasspydialogue_hermes: -> TtsSay(text='arrĂȘt cuve b deux', site_id='rpi', lang=None, id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', volume=None)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,069] rhasspydialogue_hermes: Publishing 202 bytes(s) to hermes/tts/say
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,071] rhasspydialogue_hermes: Waiting for sayFinished (id=b82354f5-482c-4679-9a3c-a32b972b7b05, timeout=10.0)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,067] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,068] rhasspywake_porcupine_hermes: Disabled
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,074] rhasspytts_cli_hermes: <- TtsSay(text='arrĂȘt cuve b deux', site_id='rpi', lang=None, id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', volume=None)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,074] rhasspyasr_kaldi_hermes: <- AsrToggleOff(site_id='rpi', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,075] rhasspyasr_kaldi_hermes: Disabled (AsrToggleReason.TTS_SAY)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,076] rhasspytts_cli_hermes: ['nanotts', '-v', 'fr-FR', '-o', '/tmp/tmpudnjtbar.wav']
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,082] rhasspywake_porcupine_hermes: <- HotwordToggleOff(site_id='rpi', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,083] rhasspywake_porcupine_hermes: Disabled
Sep 28 10:31:27 raspberrypi rhasspy[573]: Using Lingware directory: /usr/lib/rhasspy/lib/nanotts/pico/lang
Sep 28 10:31:27 raspberrypi rhasspy[573]: read: 18 bytes from stdin
Sep 28 10:31:27 raspberrypi rhasspy[573]: using lang: fr-FR
Sep 28 10:31:27 raspberrypi rhasspy[573]: wrote "/tmp/tmpudnjtbar.wav" (45868 bytes)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,161] rhasspytts_cli_hermes: Got 45868 byte(s) of WAV data
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,162] rhasspytts_cli_hermes: -> AudioPlayBytes(45868 byte(s)) to hermes/audioServer/rpi/playBytes/b82354f5-482c-4679-9a3c-a32b972b7b05
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,168] rhasspytts_cli_hermes: Waiting for play finished (timeout=1.682)
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,177] rhasspyspeakers_cli_hermes: <- AudioPlayBytes(45868 byte(s))
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,179] rhasspyspeakers_cli_hermes: ['aplay', '-q', '-t', 'wav', '-D', 'plughw:CARD=ArrayUAC10,DEV=0']
Sep 28 10:31:27 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:27,183] rhasspyspeakers_cli_hermes: Final volume is 0.5 (master=0.5)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,758] rhasspyspeakers_cli_hermes: -> AudioPlayFinished(id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='b82354f5-482c-4679-9a3c-a32b972b7b05')
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,759] rhasspyspeakers_cli_hermes: Publishing 99 bytes(s) to hermes/audioServer/rpi/playFinished
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,769] rhasspydialogue_hermes: <- AudioPlayFinished(id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='b82354f5-482c-4679-9a3c-a32b972b7b05')
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,770] rhasspytts_cli_hermes: <- AudioPlayFinished(id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='b82354f5-482c-4679-9a3c-a32b972b7b05')
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,777] rhasspytts_cli_hermes: -> TtsSayFinished(site_id='rpi', id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,777] rhasspytts_cli_hermes: Publishing 143 bytes(s) to hermes/tts/sayFinished
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,786] rhasspydialogue_hermes: <- TtsSayFinished(site_id='rpi', id='b82354f5-482c-4679-9a3c-a32b972b7b05', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262')
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,792] rhasspydialogue_hermes: -> HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,793] rhasspydialogue_hermes: Publishing 37 bytes(s) to hermes/hotword/toggleOn
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,798] rhasspydialogue_hermes: -> AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,799] rhasspydialogue_hermes: Publishing 37 bytes(s) to hermes/asr/toggleOn
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,803] rhasspydialogue_hermes: Listening for session rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,808] rhasspydialogue_hermes: -> AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id=None, intent_filter=None)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,809] rhasspydialogue_hermes: Publishing 203 bytes(s) to hermes/asr/startListening
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,812] rhasspyasr_kaldi_hermes: <- AsrToggleOn(site_id='rpi', reason=<AsrToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,814] rhasspyasr_kaldi_hermes: Enabled
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,812] rhasspywake_porcupine_hermes: <- HotwordToggleOn(site_id='rpi', reason=<HotwordToggleReason.TTS_SAY: 'ttsSay'>)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,813] rhasspywake_porcupine_hermes: Still disabled: {<HotwordToggleReason.DIALOGUE_SESSION: 'dialogueSession'>}
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,822] rhasspyasr_kaldi_hermes: <- AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id=None, intent_filter=None)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,818] rhasspymicrophone_cli_hermes: <- AsrStartListening(site_id='rpi', session_id='rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262', lang=None, stop_on_silence=True, send_audio_captured=True, wakeword_id=None, intent_filter=None)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,820] rhasspymicrophone_cli_hermes: Disable UDP output
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,824] rhasspyasr_kaldi_hermes: Creating new transcriber session rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,827] rhasspyasr_kaldi.transcribe: Using kaldi at /usr/lib/rhasspy/lib/kaldi
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,828] rhasspyasr_kaldi_hermes: Starting listening (session_id=rpi-tournesol_raspberry-pi-bce96ba5-fba0-41ef-9df3-78963a183262)
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,830] rhasspyasr_kaldi.transcribe: Creating FIFO at /tmp/tmptmlp35tg/chunks.fifo
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,831] rhasspyasr_kaldi.transcribe: ['/usr/lib/rhasspy/lib/kaldi/online2-cli-nnet3-decode-faster-confidence', '--config=/home/pi/.config/rhasspy/profiles/fr/kaldi/model/online/conf/online.conf', '--max-active=7000', '--lattice-beam=8.0', '--acoustic-scale=1.0', '--beam=24.0', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/model/final.mdl', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/HCLG.fst', '/home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/words.txt', '/tmp/tmptmlp35tg/chunks.fifo']
Sep 28 10:31:28 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:28,868] rhasspyasr_kaldi_hermes: Receiving audio
Sep 28 10:31:28 raspberrypi rhasspy[573]: /usr/lib/rhasspy/lib/kaldi/online2-cli-nnet3-decode-faster-confidence --config=/home/pi/.config/rhasspy/profiles/fr/kaldi/model/online/conf/online.conf --max-active=7000 --lattice-beam=8.0 --acoustic-scale=1.0 --beam=24.0 /home/pi/.config/rhasspy/profiles/fr/kaldi/model/model/final.mdl /home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/HCLG.fst /home/pi/.config/rhasspy/profiles/fr/kaldi/model/graph/words.txt /tmp/tmptmlp35tg/chunks.fifo
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:183) Computing derived variables for iVector extractor
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:ComputeDerivedVars():ivector-extractor.cc:204) Done.
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanNodes():nnet-nnet.cc:948) Removed 1 orphan nodes.
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:RemoveOrphanComponents():nnet-nnet.cc:847) Removing 2 orphan components.
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:Collapse():nnet-utils.cc:1488) Added 1 components, removed 2
Sep 28 10:31:29 raspberrypi rhasspy[573]: LOG (online2-cli-nnet3-decode-faster-confidence[5.5]:CompileLooped():nnet-compile-looped.cc:345) Spent 0.0793819 seconds in looped compilation.
Sep 28 10:31:29 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:29,653] rhasspyasr_kaldi.transcribe: ready
Sep 28 10:31:29 raspberrypi rhasspy[573]: [DEBUG:2021-09-28 10:31:29,653] rhasspyasr_kaldi.transcribe: Decoder started