@koan
Here are three logs:
First log:
Internal MQTT-broker activated , after saying hotword, intent is recognized:
[DEBUG:2020-03-12 19:23:59,357] rhasspyserver_hermes: -> AsrToggleOn(siteId='voltron')
[DEBUG:2020-03-12 19:23:59,355] rhasspyserver_hermes: -> HotwordToggleOn(siteId='voltron')
[DEBUG:2020-03-12 19:23:59,349] rhasspyserver_hermes: Handling AudioPlayFinished (topic=hermes/audioServer/voltron/playFinished, id=13f11d15-1a61-4541-93a1-407e24e0f5a2)
[DEBUG:2020-03-12 19:23:59,348] rhasspyserver_hermes: <- AudioPlayFinished(id='593af412-2553-482a-b2ee-7cdf21c16964', sessionId='')
[DEBUG:2020-03-12 19:23:59,347] rhasspyserver_hermes: Received 63 byte(s) on hermes/audioServer/voltron/playFinished
[ERROR:2020-03-12 19:23:58,622] __main__:
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/quart/app.py", line 1594, in full_dispatch_websocket
result = await self.dispatch_websocket(websocket_context)
File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/quart/app.py", line 1639, in dispatch_websocket
return await handler(**websocket_.view_args)
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__main__.py", line 1766, in wrapper
return await func(queue, *_args, **kwargs)
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__main__.py", line 1890, in api_ws_intent
message = await queue.get()
File "/usr/lib/python3.6/asyncio/queues.py", line 167, in get
yield from getter
concurrent.futures._base.CancelledError
[DEBUG:2020-03-12 19:23:58,619] __main__: Sent 402 char(s) to websocket
[DEBUG:2020-03-12 19:23:58,616] __main__: Sent 402 char(s) to websocket
[DEBUG:2020-03-12 19:23:58,616] rhasspyserver_hermes: Handling NluIntent (topic=hermes/intent/Zeit, id=a8479673-7d0f-41d7-ae66-4886ca3e290b)
[DEBUG:2020-03-12 19:23:58,615] rhasspyserver_hermes: Handling NluIntent (topic=hermes/intent/Zeit, id=ad38950f-8596-480c-b5be-4013d9568e1f)
[DEBUG:2020-03-12 19:23:58,614] rhasspyserver_hermes: Handling NluIntent (topic=hermes/intent/Zeit, id=e6d0c31f-3f4e-4da2-b865-af2011230eb6)
[DEBUG:2020-03-12 19:23:58,613] rhasspyserver_hermes: <- NluIntent(input='wie spät ist es', intent=Intent(intentName='Zeit', confidenceScore=1.0), slots=[Slot(entity='state', slotName='state', confidence=1, raw_value='spät', value='spät', range=SlotRange(start=4, end=8, raw_start=4, raw_end=8))], id='', siteId='voltron', sessionId='voltron-default-2d57ec6f-a8ef-4210-93b3-33c24e8b3965', customData='', asrTokens=['wie', 'spät', 'ist', 'es'], asrConfidence=1.0, wakewordId='default', rawAsrTokens=['wie', 'spät', 'ist', 'es'])
[DEBUG:2020-03-12 19:23:58,612] rhasspyserver_hermes: Received 536 byte(s) on hermes/intent/Zeit
[DEBUG:2020-03-12 19:23:58,567] rhasspyserver_hermes: <- AudioPlayBytes(119908 byte(s))
[DEBUG:2020-03-12 19:23:58,567] rhasspyserver_hermes: Received 119908 byte(s) on hermes/audioServer/voltron/playBytes/593af412-2553-482a-b2ee-7cdf21c16964
Second log:
External MQTT-broker activated and just restarted Rhasspy:
[DEBUG:2020-03-12 19:25:25,349] rhasspyprofile.download: speech_to_text.pocketsphinx.mix_weight >0 0 = False
[DEBUG:2020-03-12 19:25:25,348] rhasspyprofile.download: speech_to_text.kaldi.open_transcription True False = False
[DEBUG:2020-03-12 19:25:25,347] rhasspyprofile.download: speech_to_text.pocketsphinx.open_transcription True False = False
[DEBUG:2020-03-12 19:25:25,347] rhasspyprofile.download: Skipping kaldi/model/ivectors_test_hires/conf/splice.conf (/profiles/de/kaldi/model/ivectors_test_hires/conf/splice.conf)
[DEBUG:2020-03-12 19:25:25,346] rhasspyprofile.download: Skipping kaldi/model/ivectors_test_hires/conf/ivector_extractor.conf (/profiles/de/kaldi/model/ivectors_test_hires/conf/ivector_extractor.conf)
[DEBUG:2020-03-12 19:25:25,346] rhasspyprofile.download: Skipping kaldi/model/ivectors_test_hires/conf/online_cmvn.conf (/profiles/de/kaldi/model/ivectors_test_hires/conf/online_cmvn.conf)
[DEBUG:2020-03-12 19:25:25,345] rhasspyprofile.download: Skipping kaldi/model/extractor/final.ie (/profiles/de/kaldi/model/extractor/final.ie)
[DEBUG:2020-03-12 19:25:25,345] rhasspyprofile.download: Skipping kaldi/model/extractor/final.dubm (/profiles/de/kaldi/model/extractor/final.dubm)
[DEBUG:2020-03-12 19:25:25,344] rhasspyprofile.download: Skipping kaldi/model/extractor/splice_opts (/profiles/de/kaldi/model/extractor/splice_opts)
[DEBUG:2020-03-12 19:25:25,344] rhasspyprofile.download: Skipping kaldi/model/extractor/global_cmvn.stats (/profiles/de/kaldi/model/extractor/global_cmvn.stats)
[DEBUG:2020-03-12 19:25:25,343] rhasspyprofile.download: Skipping kaldi/model/extractor/online_cmvn.conf (/profiles/de/kaldi/model/extractor/online_cmvn.conf)
[DEBUG:2020-03-12 19:25:25,342] rhasspyprofile.download: Skipping kaldi/model/extractor/final.mat (/profiles/de/kaldi/model/extractor/final.mat)
[DEBUG:2020-03-12 19:25:25,342] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc_hires.conf (/profiles/de/kaldi/model/conf/mfcc_hires.conf)
[DEBUG:2020-03-12 19:25:25,311] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc.conf (/profiles/de/kaldi/model/conf/mfcc.conf)
[DEBUG:2020-03-12 19:25:25,310] rhasspyprofile.download: Skipping kaldi/model/conf/online_cmvn.conf (/profiles/de/kaldi/model/conf/online_cmvn.conf)
[DEBUG:2020-03-12 19:25:25,310] rhasspyprofile.download: Skipping kaldi/model/phones/silence_phones.txt (/profiles/de/kaldi/model/phones/silence_phones.txt)
[DEBUG:2020-03-12 19:25:25,309] rhasspyprofile.download: Skipping kaldi/model/phones/optional_silence.txt (/profiles/de/kaldi/model/phones/optional_silence.txt)
[DEBUG:2020-03-12 19:25:25,308] rhasspyprofile.download: Skipping kaldi/model/phones/extra_questions.txt (/profiles/de/kaldi/model/phones/extra_questions.txt)
[DEBUG:2020-03-12 19:25:25,308] rhasspyprofile.download: Skipping kaldi/model/phones/nonsilence_phones.txt (/profiles/de/kaldi/model/phones/nonsilence_phones.txt)
[DEBUG:2020-03-12 19:25:25,307] rhasspyprofile.download: Skipping kaldi/model/model/tree (/profiles/de/kaldi/model/model/tree)
[DEBUG:2020-03-12 19:25:25,307] rhasspyprofile.download: Skipping kaldi/model/model/final.mdl (/profiles/de/kaldi/model/model/final.mdl)
[DEBUG:2020-03-12 19:25:25,306] rhasspyprofile.download: Skipping kaldi/model/model/cmvn_opts (/profiles/de/kaldi/model/model/cmvn_opts)
[DEBUG:2020-03-12 19:25:25,306] rhasspyprofile.download: Skipping kaldi/g2p.fst (/profiles/de/kaldi/g2p.fst)
[DEBUG:2020-03-12 19:25:25,305] rhasspyprofile.download: Skipping kaldi/base_dictionary.txt (/profiles/de/kaldi/base_dictionary.txt)
[DEBUG:2020-03-12 19:25:25,304] rhasspyprofile.download: speech_to_text.system kaldi kaldi = True
[DEBUG:2020-03-12 19:25:25,304] rhasspyprofile.download: speech_to_text.system pocketsphinx kaldi = False
[DEBUG:2020-03-12 19:25:25,303] rhasspyprofile.download: wake.system precise snowboy = False
[ERROR:2020-03-12 19:25:25,148] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 950, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:25:25,146] rhasspyserver_hermes: Received 0 byte(s) on hermes/asr/textCaptured
[ERROR:2020-03-12 19:25:25,142] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1040, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:25:25,126] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[ERROR:2020-03-12 19:25:25,117] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1040, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:25:25,115] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[DEBUG:2020-03-12 19:25:25,109] rhasspyserver_hermes: <- AsrAudioCaptured(0 byte(s))
[DEBUG:2020-03-12 19:25:25,107] rhasspyserver_hermes: Received 0 byte(s) on rhasspy/asr/voltron/voltron/audioCaptured
[ERROR:2020-03-12 19:25:25,068] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 926, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:25:25,067] rhasspyserver_hermes: Received 0 byte(s) on hermes/nlu/intentNotRecognized
[DEBUG:2020-03-12 19:25:25,058] rhasspyserver_hermes: Subscribed to hermes/asr/textCaptured
[DEBUG:2020-03-12 19:25:25,056] rhasspyserver_hermes: Subscribed to hermes/hotword/+/detected
[DEBUG:2020-03-12 19:25:25,052] rhasspyserver_hermes: Subscribed to hermes/intent/#
[DEBUG:2020-03-12 19:25:25,048] rhasspyserver_hermes: Subscribed to rhasspy/asr/voltron/voltron/audioCaptured
[INFO:2020-03-12 19:25:25,042] __main__: Started
[DEBUG:2020-03-12 19:25:25,041] rhasspyserver_hermes: Subscribed to hermes/nlu/intentNotRecognized
[DEBUG:2020-03-12 19:25:24,031] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 0)
[DEBUG:2020-03-12 19:25:24,030] rhasspyserver_hermes: Starting core
[DEBUG:2020-03-12 19:25:24,027] rhasspyprofile.profile: Loading default profile settings from /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/defaults.json
[DEBUG:2020-03-12 19:25:24,026] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-12 19:25:24,024] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-12 19:25:23,995] rhasspyserver_hermes: Shutting down core
[DEBUG:2020-03-12 19:25:23,994] __main__: Restarting Rhasspy
[DEBUG:2020-03-12 19:25:23,743] __main__: Re-generating /profiles/de/docker-compose.yml
[DEBUG:2020-03-12 19:25:23,740] rhasspysupervisor: Intent handling disabled (system=dummy)
[DEBUG:2020-03-12 19:25:23,726] __main__: Re-generating /profiles/de/supervisord.conf
[DEBUG:2020-03-12 19:25:23,724] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-12 19:25:23,721] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-12 19:25:23,712] __main__: Wrote profile to /profiles/de/profile.json
Third log:
External MQTT-broker is still activated and wake-up-button was pressed
-> popup “Listening for command” appears, but no reaction after speaking
-> after timeout? popup “Listening for command” is closed
[ERROR:2020-03-12 19:27:26,839] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 950, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:27:26,838] rhasspyserver_hermes: Received 0 byte(s) on hermes/asr/textCaptured
[ERROR:2020-03-12 19:27:26,832] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1040, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:27:26,831] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[ERROR:2020-03-12 19:27:26,825] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1040, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:27:26,824] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[DEBUG:2020-03-12 19:27:26,819] rhasspyserver_hermes: <- AsrAudioCaptured(0 byte(s))
[DEBUG:2020-03-12 19:27:26,818] rhasspyserver_hermes: Received 0 byte(s) on rhasspy/asr/voltron/voltron/audioCaptured
[ERROR:2020-03-12 19:27:26,800] rhasspyserver_hermes: on_message
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 926, in on_message
json_payload = json.loads(msg.payload)
File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
return _default_decoder.decode(s)
File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
obj, end = self.raw_decode(s, idx=_w(s, 0).end())
File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
[DEBUG:2020-03-12 19:27:26,799] rhasspyserver_hermes: Received 0 byte(s) on hermes/nlu/intentNotRecognized
[DEBUG:2020-03-12 19:27:26,793] rhasspyserver_hermes: Subscribed to hermes/asr/textCaptured
[DEBUG:2020-03-12 19:27:26,791] rhasspyserver_hermes: Subscribed to hermes/hotword/+/detected
[DEBUG:2020-03-12 19:27:26,789] rhasspyserver_hermes: Subscribed to hermes/intent/#
[DEBUG:2020-03-12 19:27:26,787] rhasspyserver_hermes: Subscribed to rhasspy/asr/voltron/voltron/audioCaptured
[DEBUG:2020-03-12 19:27:26,785] rhasspyserver_hermes: Subscribed to hermes/nlu/intentNotRecognized
[ERROR:2020-03-12 19:27:13,748] __main__:
Traceback (most recent call last):
File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/quart/app.py", line 1471, in full_dispatch_request
result = await self.dispatch_request(request_context)
File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/quart/app.py", line 1519, in dispatch_request
return await handler(**request_.view_args)
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__main__.py", line 748, in api_listen_for_command
async for response in core.publish_wait(handle_captured(), messages, topics):
File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 837, in publish_wait
_, result = await asyncio.wait_for(result_awaitable, timeout_seconds)
File "/usr/lib/python3.6/asyncio/tasks.py", line 362, in wait_for
raise futures.TimeoutError()
concurrent.futures._base.TimeoutError
[DEBUG:2020-03-12 19:26:43,715] rhasspyserver_hermes: -> AsrStartListening(siteId='voltron', sessionId='8f864bbc-3e49-4760-a380-2ffcb1ca944f', stopOnSilence=True, sendAudioCaptured=True, wakewordId='')
[DEBUG:2020-03-12 19:26:43,714] rhasspyserver_hermes: Subscribed to hermes/error/asr
[DEBUG:2020-03-12 19:26:43,713] __main__: Waiting for transcription (sessionId=8f864bbc-3e49-4760-a380-2ffcb1ca944f)
Hope, this helps?