Documentation Preview for 2.5

Jorg_Baumann · March 11, 2020, 10:12pm

Hi,
I’m just playing with Rhasspy 2.5 Voltron with German profile, installed on a Raspi 4 with docker.
I have a problem to connect to an external MQTT-broker ( 192.168.192.29:1891) :
after saving the profile and restart Rhasspy there’s an error-message that the MQTT-broker could not be connected .

Here is the log:

[ERROR:1551627] __main__: Failed to connect to MQTT broker
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 1137, in api_restart
    await start_rhasspy()
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__main__.py", line 424, in start_rhasspy
    await core.start()
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 230, in start
    raise RuntimeError("Failed to connect to MQTT broker")
RuntimeError: Failed to connect to MQTT broker
[CRITICAL:1551626] rhasspyserver_hermes: Failed to connect to MQTT broker (192.168.192.29:1891)
[ERROR:1550623] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1550622] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 9)
[ERROR:1549619] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1549618] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 8)
[ERROR:1548613] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1548612] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 7)
[ERROR:1547608] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1547607] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 6)
[ERROR:1546603] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1546603] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 5)
[ERROR:1545599] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1545598] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 4)
[ERROR:1544595] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1544594] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 3)
[ERROR:1543592] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1543592] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 2)
[ERROR:1542588] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1542587] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 1)
[ERROR:1541585] rhasspyserver_hermes: mqtt connect
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 217, in start
    self.client.connect(self.host, self.port)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 936, in connect
    bind_address, bind_port, clean_start, properties)
  File "/usr/lib/rhasspy-voltron/.venv/lib/python3.6/site-packages/paho/mqtt/client.py", line 1002, in connect_async
    if port <= 0:
TypeError: '<=' not supported between instances of 'str' and 'int'
[DEBUG:1541584] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 0)
[DEBUG:1541583] rhasspyserver_hermes: Starting core
[DEBUG:1541132] rhasspyprofile.profile: Loading default profile settings from /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/defaults.json
[DEBUG:1537572] rhasspyserver_hermes: Shutting down core
[DEBUG:1537571] __main__: Restarting Rhasspy
[DEBUG:1537326] __main__: Re-generating /profiles/de/docker-compose.yml
[DEBUG:1537325] rhasspysupervisor: Intent handling disabled (system=dummy)
[DEBUG:1537315] __main__: Re-generating /profiles/de/supervisord.conf
[DEBUG:1534551] __main__: Wrote profile to /profiles/de/profile.json

This is curious (for me) because on an other Raspi with Rhasspy 2.4.18, the same MQTT-broker is reachable without problems.

I know, this is a pre-release of voltron, but I think connecting to an external MQTT-broker is essential.
Perhaps I’m doing something wrong?
Any suggestions?

koan · March 12, 2020, 8:24am

I have a 2.5-prerelease setup working here with a base and satellite communicating over MQTT. I notice that you are using a non-standard MQTT port, I’m using the default one (1883). Rhasspy seems to interpret the port from the config file as a string if you use a non-standard one, while it has to be interpreted as a number.

Oh I see that @synesthesiam has already fixed it.

Jorg_Baumann · March 12, 2020, 6:40pm

Thanks @koan for your response and @synesthesiam for the quick fix.

Now the external MQTT-broker is connected and Rhasspy subscribes to the topics, but unfortunately the Rhasspy test-page seems to be “dead”.
When I press the wake-up-button, the popup “Listening for command” appears, but when I speak a command, nothing happens.

When I switch back to internal MQTT-broker, the test-page is working fine:
Rhasspy reacts even to wake-up-button and also to wakeword (snowboy.umtl).

I can reproduce this problem by switching between internal / external MQTT-Broker.

Maybe that’s because I’m using not the MQTT standard port 1883 ?
(but I definitely can not use the standard port)
As I said before: no problems with Rhasspy 2.4.18 and MQTT-port 1891.

Anyway, I’ll be patient, until final Version 2.5 is released.

koan · March 12, 2020, 6:54pm

Can you share some logs with the internal vs external MQTT broker?

Jorg_Baumann · March 12, 2020, 7:53pm

@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?

koan · March 12, 2020, 8:17pm

Mmmm I’m not sure what is happening here… I can’t replicate this in my setup either.

Jorg_Baumann · March 12, 2020, 8:24pm

Doesn’t matter, thanks anyway for trying to help.
I’ll wait for the final release of Voltron.

synesthesiam · March 12, 2020, 8:45pm

Thanks for the logs, @Jorg_Baumann. I’m hoping this is one of those errors that will magically disappear in the next release (building now)

Jorg_Baumann · March 13, 2020, 12:49pm

@synesthesiam
Hi,
sorry, that I wasn’t patient, I pulled the latest pre-release once again, but the magician was not present

But i did a new test:
I’ve installed a MOSQUITTO-broker with standard MQTT-port 1883 on the same machine, where the other MQTT-broker with MQTT-port 1891 causes problems:

With connection to MOSQUITTO (1883), Rhasspy works fine.
With connection to the other Broker (1891), Rhasspy does not respond to commands.

Here are two logs:
First log with connection to MOSQUITTO, Port 1883, Rhasspy works

[DEBUG:2020-03-13 12:04:46,926] rhasspyprofile.download: speech_to_text.pocketsphinx.mix_weight >0 0 = False
[DEBUG:2020-03-13 12:04:46,925] rhasspyprofile.download: speech_to_text.kaldi.open_transcription True False = False
[DEBUG:2020-03-13 12:04:46,925] rhasspyprofile.download: speech_to_text.pocketsphinx.open_transcription True False = False
[DEBUG:2020-03-13 12:04:46,924] rhasspyprofile.download: Skipping kaldi/model/ivectors_test_hires/conf/splice.conf (/profiles/de/kaldi/model/ivectors_test_hires/conf/splice.conf)
[DEBUG:2020-03-13 12:04:46,923] 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-13 12:04:46,922] 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-13 12:04:46,921] rhasspyprofile.download: Skipping kaldi/model/extractor/final.ie (/profiles/de/kaldi/model/extractor/final.ie)
[DEBUG:2020-03-13 12:04:46,921] rhasspyprofile.download: Skipping kaldi/model/extractor/final.dubm (/profiles/de/kaldi/model/extractor/final.dubm)
[DEBUG:2020-03-13 12:04:46,920] rhasspyprofile.download: Skipping kaldi/model/extractor/splice_opts (/profiles/de/kaldi/model/extractor/splice_opts)
[DEBUG:2020-03-13 12:04:46,919] rhasspyprofile.download: Skipping kaldi/model/extractor/global_cmvn.stats (/profiles/de/kaldi/model/extractor/global_cmvn.stats)
[DEBUG:2020-03-13 12:04:46,919] rhasspyprofile.download: Skipping kaldi/model/extractor/online_cmvn.conf (/profiles/de/kaldi/model/extractor/online_cmvn.conf)
[DEBUG:2020-03-13 12:04:46,918] rhasspyprofile.download: Skipping kaldi/model/extractor/final.mat (/profiles/de/kaldi/model/extractor/final.mat)
[DEBUG:2020-03-13 12:04:46,917] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc_hires.conf (/profiles/de/kaldi/model/conf/mfcc_hires.conf)
[DEBUG:2020-03-13 12:04:46,916] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc.conf (/profiles/de/kaldi/model/conf/mfcc.conf)
[DEBUG:2020-03-13 12:04:46,916] rhasspyprofile.download: Skipping kaldi/model/conf/online_cmvn.conf (/profiles/de/kaldi/model/conf/online_cmvn.conf)
[DEBUG:2020-03-13 12:04:46,915] rhasspyprofile.download: Skipping kaldi/model/phones/silence_phones.txt (/profiles/de/kaldi/model/phones/silence_phones.txt)
[DEBUG:2020-03-13 12:04:46,914] rhasspyprofile.download: Skipping kaldi/model/phones/optional_silence.txt (/profiles/de/kaldi/model/phones/optional_silence.txt)
[DEBUG:2020-03-13 12:04:46,913] rhasspyprofile.download: Skipping kaldi/model/phones/extra_questions.txt (/profiles/de/kaldi/model/phones/extra_questions.txt)
[DEBUG:2020-03-13 12:04:46,913] rhasspyprofile.download: Skipping kaldi/model/phones/nonsilence_phones.txt (/profiles/de/kaldi/model/phones/nonsilence_phones.txt)
[DEBUG:2020-03-13 12:04:46,912] rhasspyprofile.download: Skipping kaldi/model/model/tree (/profiles/de/kaldi/model/model/tree)
[DEBUG:2020-03-13 12:04:46,897] rhasspyprofile.download: Skipping kaldi/model/model/final.mdl (/profiles/de/kaldi/model/model/final.mdl)
[DEBUG:2020-03-13 12:04:46,896] rhasspyprofile.download: Skipping kaldi/model/model/cmvn_opts (/profiles/de/kaldi/model/model/cmvn_opts)
[DEBUG:2020-03-13 12:04:46,896] rhasspyprofile.download: Skipping kaldi/g2p.fst (/profiles/de/kaldi/g2p.fst)
[DEBUG:2020-03-13 12:04:46,895] rhasspyprofile.download: Skipping kaldi/base_dictionary.txt (/profiles/de/kaldi/base_dictionary.txt)
[DEBUG:2020-03-13 12:04:46,894] rhasspyprofile.download: speech_to_text.system kaldi kaldi = True
[DEBUG:2020-03-13 12:04:46,894] rhasspyprofile.download: speech_to_text.system pocketsphinx kaldi = False
[DEBUG:2020-03-13 12:04:46,884] rhasspyprofile.download: wake.system precise snowboy = False
[DEBUG:2020-03-13 12:04:46,619] rhasspyserver_hermes: Subscribed to hermes/hotword/+/detected
[DEBUG:2020-03-13 12:04:46,618] rhasspyserver_hermes: Subscribed to rhasspy/asr/voltron/voltron/audioCaptured
[DEBUG:2020-03-13 12:04:46,616] rhasspyserver_hermes: Subscribed to hermes/asr/textCaptured
[INFO:2020-03-13 12:04:46,614] rhasspyserver_hermes: Started
[DEBUG:2020-03-13 12:04:46,613] rhasspyserver_hermes: Subscribed to hermes/intent/#
[DEBUG:2020-03-13 12:04:46,612] rhasspyserver_hermes: Subscribed to hermes/nlu/intentNotRecognized
[DEBUG:2020-03-13 12:04:45,544] rhasspyserver_hermes: Connecting to 192.168.192.29:1883 (retries: 0)
[DEBUG:2020-03-13 12:04:45,541] rhasspyserver_hermes: Starting core
[DEBUG:2020-03-13 12:04:45,536] rhasspyprofile.profile: Loading default profile settings from /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/defaults.json
[DEBUG:2020-03-13 12:04:45,534] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-13 12:04:45,531] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-13 12:04:44,908] rhasspyserver_hermes: Shutting down core
[DEBUG:2020-03-13 12:04:44,906] rhasspyserver_hermes: Restarting Rhasspy
[DEBUG:2020-03-13 12:04:44,618] rhasspyserver_hermes: Re-generating /profiles/de/docker-compose.yml
[DEBUG:2020-03-13 12:04:44,615] rhasspysupervisor: Intent handling disabled (system=dummy)
[DEBUG:2020-03-13 12:04:44,603] rhasspyserver_hermes: Re-generating /profiles/de/supervisord.conf
[DEBUG:2020-03-13 12:04:44,601] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-13 12:04:44,597] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-13 12:04:44,589] rhasspyserver_hermes: Wrote profile to /profiles/de/profile.json

Second log with connection to the other boker, Port 1891, Rhasspy does not respond to commands

[DEBUG:2020-03-13 12:06:29,068] rhasspyprofile.download: speech_to_text.pocketsphinx.mix_weight >0 0 = False
[DEBUG:2020-03-13 12:06:29,066] rhasspyprofile.download: speech_to_text.kaldi.open_transcription True False = False
[DEBUG:2020-03-13 12:06:29,066] rhasspyprofile.download: speech_to_text.pocketsphinx.open_transcription True False = False
[DEBUG:2020-03-13 12:06:29,065] rhasspyprofile.download: Skipping kaldi/model/ivectors_test_hires/conf/splice.conf (/profiles/de/kaldi/model/ivectors_test_hires/conf/splice.conf)
[DEBUG:2020-03-13 12:06:29,064] 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-13 12:06:29,063] 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-13 12:06:29,062] rhasspyprofile.download: Skipping kaldi/model/extractor/final.ie (/profiles/de/kaldi/model/extractor/final.ie)
[DEBUG:2020-03-13 12:06:29,046] rhasspyprofile.download: Skipping kaldi/model/extractor/final.dubm (/profiles/de/kaldi/model/extractor/final.dubm)
[DEBUG:2020-03-13 12:06:29,045] rhasspyprofile.download: Skipping kaldi/model/extractor/splice_opts (/profiles/de/kaldi/model/extractor/splice_opts)
[DEBUG:2020-03-13 12:06:29,045] rhasspyprofile.download: Skipping kaldi/model/extractor/global_cmvn.stats (/profiles/de/kaldi/model/extractor/global_cmvn.stats)
[DEBUG:2020-03-13 12:06:29,044] rhasspyprofile.download: Skipping kaldi/model/extractor/online_cmvn.conf (/profiles/de/kaldi/model/extractor/online_cmvn.conf)
[DEBUG:2020-03-13 12:06:29,043] rhasspyprofile.download: Skipping kaldi/model/extractor/final.mat (/profiles/de/kaldi/model/extractor/final.mat)
[DEBUG:2020-03-13 12:06:29,043] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc_hires.conf (/profiles/de/kaldi/model/conf/mfcc_hires.conf)
[DEBUG:2020-03-13 12:06:29,042] rhasspyprofile.download: Skipping kaldi/model/conf/mfcc.conf (/profiles/de/kaldi/model/conf/mfcc.conf)
[DEBUG:2020-03-13 12:06:29,041] rhasspyprofile.download: Skipping kaldi/model/conf/online_cmvn.conf (/profiles/de/kaldi/model/conf/online_cmvn.conf)
[DEBUG:2020-03-13 12:06:29,040] rhasspyprofile.download: Skipping kaldi/model/phones/silence_phones.txt (/profiles/de/kaldi/model/phones/silence_phones.txt)
[DEBUG:2020-03-13 12:06:29,032] rhasspyprofile.download: Skipping kaldi/model/phones/optional_silence.txt (/profiles/de/kaldi/model/phones/optional_silence.txt)
[DEBUG:2020-03-13 12:06:29,031] rhasspyprofile.download: Skipping kaldi/model/phones/extra_questions.txt (/profiles/de/kaldi/model/phones/extra_questions.txt)
[DEBUG:2020-03-13 12:06:29,031] rhasspyprofile.download: Skipping kaldi/model/phones/nonsilence_phones.txt (/profiles/de/kaldi/model/phones/nonsilence_phones.txt)
[DEBUG:2020-03-13 12:06:29,030] rhasspyprofile.download: Skipping kaldi/model/model/tree (/profiles/de/kaldi/model/model/tree)
[DEBUG:2020-03-13 12:06:29,029] rhasspyprofile.download: Skipping kaldi/model/model/final.mdl (/profiles/de/kaldi/model/model/final.mdl)
[DEBUG:2020-03-13 12:06:29,028] rhasspyprofile.download: Skipping kaldi/model/model/cmvn_opts (/profiles/de/kaldi/model/model/cmvn_opts)
[DEBUG:2020-03-13 12:06:29,028] rhasspyprofile.download: Skipping kaldi/g2p.fst (/profiles/de/kaldi/g2p.fst)
[DEBUG:2020-03-13 12:06:29,027] rhasspyprofile.download: Skipping kaldi/base_dictionary.txt (/profiles/de/kaldi/base_dictionary.txt)
[DEBUG:2020-03-13 12:06:29,026] rhasspyprofile.download: speech_to_text.system kaldi kaldi = True
[DEBUG:2020-03-13 12:06:29,025] rhasspyprofile.download: speech_to_text.system pocketsphinx kaldi = False
[DEBUG:2020-03-13 12:06:29,025] rhasspyprofile.download: wake.system precise snowboy = False
[ERROR:2020-03-13 12:06:28,834] rhasspyserver_hermes: on_message
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1041, 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-13 12:06:28,832] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[DEBUG:2020-03-13 12:06:28,829] rhasspyserver_hermes: <- AsrAudioCaptured(0 byte(s))
[DEBUG:2020-03-13 12:06:28,828] rhasspyserver_hermes: Received 0 byte(s) on rhasspy/asr/voltron/voltron/audioCaptured
[ERROR:2020-03-13 12:06:28,825] 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-13 12:06:28,824] rhasspyserver_hermes: Received 0 byte(s) on hermes/asr/textCaptured
[ERROR:2020-03-13 12:06:28,819] rhasspyserver_hermes: on_message
Traceback (most recent call last):
  File "/usr/lib/rhasspy-voltron/rhasspy-server-hermes/rhasspyserver_hermes/__init__.py", line 1041, 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-13 12:06:28,818] rhasspyserver_hermes: Received 0 byte(s) on hermes/hotword/default/detected
[ERROR:2020-03-13 12:06:28,759] 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-13 12:06:28,757] rhasspyserver_hermes: Received 0 byte(s) on hermes/nlu/intentNotRecognized
[DEBUG:2020-03-13 12:06:28,726] rhasspyserver_hermes: Subscribed to hermes/hotword/+/detected
[DEBUG:2020-03-13 12:06:28,724] rhasspyserver_hermes: Subscribed to rhasspy/asr/voltron/voltron/audioCaptured
[DEBUG:2020-03-13 12:06:28,722] rhasspyserver_hermes: Subscribed to hermes/asr/textCaptured
[DEBUG:2020-03-13 12:06:28,713] rhasspyserver_hermes: Subscribed to hermes/intent/#
[INFO:2020-03-13 12:06:28,712] rhasspyserver_hermes: Started
[DEBUG:2020-03-13 12:06:28,709] rhasspyserver_hermes: Subscribed to hermes/nlu/intentNotRecognized
[DEBUG:2020-03-13 12:06:27,660] rhasspyserver_hermes: Connecting to 192.168.192.29:1891 (retries: 0)
[DEBUG:2020-03-13 12:06:27,658] rhasspyserver_hermes: Starting core
[DEBUG:2020-03-13 12:06:27,656] rhasspyprofile.profile: Loading default profile settings from /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/defaults.json
[DEBUG:2020-03-13 12:06:27,655] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-13 12:06:27,653] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-13 12:06:27,583] rhasspyserver_hermes: Shutting down core
[DEBUG:2020-03-13 12:06:27,582] rhasspyserver_hermes: Restarting Rhasspy
[DEBUG:2020-03-13 12:06:27,275] rhasspyserver_hermes: Re-generating /profiles/de/docker-compose.yml
[DEBUG:2020-03-13 12:06:27,272] rhasspysupervisor: Intent handling disabled (system=dummy)
[DEBUG:2020-03-13 12:06:27,261] rhasspyserver_hermes: Re-generating /profiles/de/supervisord.conf
[DEBUG:2020-03-13 12:06:27,259] rhasspyprofile.profile: Loading /profiles/de/profile.json
[DEBUG:2020-03-13 12:06:27,255] rhasspyprofile.profile: Loading /usr/lib/rhasspy-voltron/rhasspy-profile/rhasspyprofile/profiles/de/profile.json
[DEBUG:2020-03-13 12:06:27,249] rhasspyserver_hermes: Wrote profile to /profiles/de/profile.json

In the second log, I can see a message:

File “/usr/lib/python3.6/json/decoder.py”, line 357, in raw_decode
raise JSONDecodeError(“Expecting value”, s, err.value) from None

I don’t know if that’s the reason of the problem.

But IMHO the problem is still related to the external port 1891, because with external port 1883 everything is OK.

synesthesiam · March 14, 2020, 2:44am

I need to add some better logging, but thanks for trying it!

The error suggests an empty or malformed message is getting in on one of the subscribed topics. Does the second port have encrypted payloads or something?

Jorg_Baumann · March 14, 2020, 1:26pm

@synesthesiam
No, the port 1891 has nothing special like encryption or other things.
As I said, it is working well from another Raspi with Rhasspy V 2.4.19

j3mu5 · March 16, 2020, 8:34am

Hello everybody! I am wondering how to best handle the MQTT Broker, as I already use a broker under the standard port with password protection for my smart home devices via HomeAssistant.
When I used Snips back then and used this Broker for Snips as well, it was flooded by Snips so much that the Smart Home control by HomeAssistant didn’t work properly anymore. The solution was to build snips on a separate device with a separate broker and a MQTT bridge:

connection snipsmqtt
address IP:PORT
remote_username USERNAME
remote_password PASSWORD
remote_clientid snips
start_type automatic
notifications true
cleansession true
bridge_protocol_version mqttv311

# default setting but we explicitly set it here
try_private true

topic hermes/dialogueManager/# in
topic hermes/asr/# in
topic hermes/hotword/# out

topic hermes/intent/# out
topic hermes/asr/# out
topic hermes/hotword/# out
topic hermes/nlu/# out

topic hermes/tts/say in
topic hermes/audioServer/# in
topic hermes/tts/sayFinished in

For Rhasspy version 2.5 I would rather use all Docker images (HA, Rhasspy) on one device. In this case it should work if Rhasspy brings a second MQTT broker with a different port and I connect both brokers with a bridge, right?
Alternative: Maybe Rhasspy will not send so many messages and I can use the current broker. Would it be possible to use my current broker for Rhasspy 2.5, since it requires authentication?
Does it make sense that I test this with the (pre-)^2 release, or is it too early for that?

Bozor · March 18, 2020, 1:24am

I wanted to checkout a prereleased V2.5 on github… Actually I’m having a hard time to find it. Can I just clone this repo: V2.5?

Thanks

EDIT: I think I’ve found it --> install docs

I’m stuck at installing the docker image with the following error message:

Does anyone faced the same issue?

Jorg_Baumann · March 18, 2020, 4:15pm

For me it’s working with this command:
docker run -d -p 12101:12101 --restart unless-stopped -v "$HOME/.config/rhasspy/profiles:/profiles" --device /dev/snd:/dev/snd rhasspy/rhasspy:2.5.0-pre --user-profiles /profiles --profile de

Perhaps there are too much spaces in your command ?

Bozor · March 18, 2020, 5:52pm

Nope. I’ve thought of this as well and tried it.

Still the same error.

Martin_Maier · March 18, 2020, 6:46pm

Since 2 days the latest docker image of 2.5.0-pre does not work for me on two different machines ( the first is an ubuntu server 18.04 and second a Pi3. After the install of the image the rhasspy-voltron container is permanently restarting and the command ‘docker logs container’ says:

find: relocation error: /usr/lib/rhasspy-voltron/.venv/tools/libpthread.so.0: symbol __libc_dl_error_tsd version GLIBC_PRIVATE not defined in file libc.so.6 with link time reference
python3: relocation error: /usr/lib/rhasspy-voltron/.venv/tools/libpthread.so.0: symbol __libc_dl_error_tsd version GLIBC_PRIVATE not defined in file libc.so.6 with link time reference<

Jorg_Baumann · March 18, 2020, 7:15pm

Hi,
the same behavior now with me ( and Bozor? ), container is always restarting.
An older Version of Voltron (2.5.0-pre) worked on my Pi4.
I think we have to wait for a new release ?

synesthesiam · March 18, 2020, 7:54pm

I made a mistake and had tested the wrong image before uploading. Will be fixing this today.

synesthesiam · March 18, 2020, 8:00pm

Yes, Rhasspy 2.5 brings its own broker inside the Docker image (mosquitto). This runs on port 12183 by default, so you can expose this port and bridge your brokers if you’d like.

If the flood came from audio messages, Rhasspy 2.5 has the option of sending audio via UDP before being woken up. This will keep the pre-wake audio off your broker, and only use MQTT to stream audio during an actual voice command.

Bozor · March 19, 2020, 9:31am

Thanks for fixing the image is working again.