The session was ended because one of the component didn't respond in a timely manner

I think this was asked before but anyone has other solutions / ways to diagnosis?

I can’t get beyond wakeword. Sound test passes / all the snips services running, and created an intent with action . (along with a few existing actions) yet no luck.

$ sam watch

[21:58:24] Watching on localhost:1883 (MQTT)

[21:58:27] [Hotword] detected on site default, for model hey_snips

[21:58:27] [Asr] was asked to stop listening on site default

[21:58:27] [Hotword] was asked to toggle itself ‘off’ on site default

[21:58:27] [Dialogue] session with id ‘83c7ca85-6f74-45d3-b03c-ef0e5c81fe18’ was started on site default

[21:58:27] [AudioServer] was asked to play a wav of 41.1 kB with id ‘c1494432-71b8-4a80-a4e5-06f1ae9f782a’ on site default

[21:58:42] [Dialogue] session with id ‘83c7ca85-6f74-45d3-b03c-ef0e5c81fe18’ was ended on site default. The session was ended because one of the component didn’t respond in a timely manner

[21:58:42] [Asr] was asked to stop listening on site default

[21:58:42] [Hotword] was asked to toggle itself ‘on’ on site default

you should use the search… this has been asked and answered 100’s of times

First test your setup with some apps from the Snips Store. If that doesn’t work, tell us which apps you tried and show us the code of the app you created. Otherwise we don’t have enough information to help you.

Thank you for your responses! @koan Here’s the list of apps I’m using. (@ozie is this yours? :slight_smile:

(I tried Google search with the msg but I couldn’t find any exact match besides the one I linked.)

$ sam install assistant -i proj_XrBXkQ16akNM
Downloading assistant

NLU training done
ASR training done
:heavy_check_mark: Deploying assistant to 192.168.0.31…
Checking for actions
Python skill lifidea.AppTest has 1 snippet:
lifidea
Python skill ozie.Calculations has 1 snippet:
ozie
Cloning skill CrystalMethod.Wikipedia from url: https://gitlab.smb-tec.com/snips.ai/skills/snips-wikipedia.git
Already up-to-date.

Generating actions based on the snippets, copying the actions in /var/lib/snips/skills

Setting up Python actions
:heavy_check_mark: Generating virtual environment for snips-wikipedia
:heavy_check_mark: Generating virtual environment for ozie.Calculations
:heavy_check_mark: Generating virtual environment for lifidea.AppTest

Checking actions for end-user parameters
i Action ozie.Calculations contains end-user parameters that need to be configured
? Please enter a value for the parameter [US_or_UK_metric] US
? Please enter a value for the parameter [exchange_rate_api] 5QRqAdT3RaEP3AYeFyKc7sDPajWdTq
? Please enter a value for the parameter [default_currency] AUD
i You can change these values later by editing the file located at /var/lib/snips/skills/ozie.Calculations/config.ini
Relaunching snips-skill-server
:heavy_check_mark: Snips assistant is now running. Say hey_snips to start!
i Run sam watch to see the logs

What is the output of:

sam status

tail -f /var/log/syslog (while talking to Snips)

$ tail -f /var/log/system.log

I don’t see any new msg (while talking to Snips)

$ sam status

Connected to device 192.168.0.31

OS version … Raspbian GNU/Linux 9 (stretch)
Installed assistant … pibot
Language … en
Hotword … hey_snips
ASR engine … snips
Status … Live

Service status:

snips-analytics … 0.60.8 (running)
snips-asr … 0.60.8 (running)
snips-audio-server … 0.60.8 (running)
snips-dialogue … 0.60.8 (running)
snips-hotword … 0.60.8 (running)
snips-nlu … 0.60.8 (running)
snips-skill-server … 0.60.8 (running)
snips-tts … 0.60.8 (running)

Here’s what I see

It’s /var/log/syslog

I have mac so no such file exists but here’s what I see from Rpi:

Dec 24 10:45:34 raspberrypi snips-hotword[2030]: INFO:snips_hotword_hermes : Hotword detected: site_id:default model:hey_snips (209ms ago)
Dec 24 10:45:34 raspberrypi snips-dialogue[1975]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn off Hotword
Dec 24 10:45:34 raspberrypi snips-dialogue[1975]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [20687558-efb8-4e13-add5-5686963f182a]: The session has been created because the site “default” is free
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: INFO:snips_audio_portaudio : Playing “0abe9484-1fe6-4b2c-bacb-60f7524b56d4” using output “default”, wav spec: WavSpec { channels: 2, sample_rate: 22050, bits_per_sample: 16, sample_format: Int }
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: Expression ‘alsa_snd_pcm_hw_params_set_buffer_size_near( self->pcm, hwParams, &bufSz )’ failed in ‘src/hostapi/alsa/pa_linux_alsa.c’, line: 2079
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: Expression ‘PaAlsaStreamComponent_FinishConfigure( &self->playback, hwParamsPlayback, outParams, self->primeBuffers, realSr, outputLatency )’ failed in ‘src/hostapi/alsa/pa_linux_alsa.c’, line: 2733
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: Expression ‘PaAlsaStream_Configure( stream, inputParameters, outputParameters, sampleRate, framesPerBuffer, &inputLatency, &outputLatency, &hostBufferSizeMode )’ failed in ‘src/hostapi/alsa/pa_linux_alsa.c’, line: 2838
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: thread ‘ai.snips.audio.portaudio.player’ panicked at ‘called Result::unwrap() on an Err value: “SendError(…)”’, libcore/result.rs:1009:5
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: ERROR:snips_audio_server_hermes : could not play “0abe9484-1fe6-4b2c-bacb-60f7524b56d4”
Dec 24 10:45:34 raspberrypi snips-audio-server[2066]: -> caused by: UnanticipatedHostError
Dec 24 10:45:34 raspberrypi snips-hotword[2030]: INFO:snips_hotword_lib::audio : Audio thread for site default stopped.
Dec 24 10:45:49 raspberrypi snips-dialogue[1975]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [20687558-efb8-4e13-add5-5686963f182a]: closing session which was active: Timeout
Dec 24 10:45:49 raspberrypi snips-dialogue[1975]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn on Hotword
Dec 24 10:45:49 raspberrypi snips-hotword[2030]: INFO:snips_hotword_lib::audio : Audio thread for site default started.

I searched again with ‘alsa_snd_pcm_hw_params_set_buffer_size_near’ and there’s a suggestion about setting ‘sound_feedback_disabled_default = true’ not sure what this actually does (man page is not very helpful https://docs.snips.ai/guides/platform-articles/platform-configuration ) but after setting this it seem to work now! Thanks @koan

that setting enables or disabled the “beep” sound snips can make when it hears the wake word

@ozie Thanks! I’m using RaspiAudio with built in mic & speaker. Anything I need to do to avoid the error?

I am searching for quite some time now. The forum search shows:
" 37 results forSession didn’t respond in a timely manner"
but none of them gives me any hint to solve that problem.
So would you please be so kind to show the solution? That would be a great help for so many of us … :slight_smile:

This message tells your that:

1 - Good news, the intent was understood and a dialogue session was opened for your action code to answer to. (It is the base contract of the Dialogue. Every session opened MUST be answered to.

2 - You don’t have any action code running to answer to the Dialogue engine with and endSession or a continueSession.

Here is an illustration of a simple python script using hermes-python running in your terminal can end an opened session.

The snips-skill-server was designed to automate for you the launch and setup of actions script.

Advanced user could also not use it all and develop their own application framework.

So, the way to proceed, if you are using the snips-skill-server is to:

  • Check if the snips-skill-server is installed (sudo apt install snips-skill-server)
  • Check if you have some actions scripts installed (ls -l /var/lib/snips/skills)
  • Because the snips-skill-server run under the user _snips-skills, all the subfolders present MUST be owned by _snips-skills
  • Some skills may need to be configured. (sudo -u _snips-skills snips-skill-server install_skills)

It also possible to check the output of the snips-skill-server by running

sudo journalctl -u snips-skill-server

If you want more verbosity run

sudo -u _snips-skills snips-skill-server -vvv

Also, you can also run directly your scripts to check their output.

cd /var/lib/snips/skills/my-action-script-folder

# if a venv is needed
source venv/bin/activate

python my-action-script.py 

Hopes that help

I’m battling the same issue and I feel like I am running in circles.

I have hotword detection working.

I could see in snips-watch -vvv when I spoke the command recognition of the command and then it would end because a component didn’t respond.

I’ve been directed to not use SAM to install the assistants. So I’ve been that manually and I assume since I’m seeing some action in snips-watch that things are ok(ish?).

Then I found this post. I tried this:

cd /var/lib/snips/skills/my-action-script-folder
source venv/bin/activate
python my-action-script.py

And things hung so I did a CTRL+C and this was the output:

Traceback (most recent call last):
  File "action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py", line 45, in <module>
    h.subscribe_intent("SirBuildsALot7:hello", subscribe_intent_callback) \
  File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
    self.loop_forever()
  File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 317, in loop_forever
    sleep(.1)
KeyboardInterrupt

Then I noticed the SirBuildsALot7.Greetings and sub folders were not owned by _snips-skills so I fixed that, and did a restart of snips-* just to be safe.

I tried again but I got the same results.
Then I tried without activating the source but the results were the same.

Then I went back to snips-watch -vvv and did a ‘Hey Snips’ which was detected but the spoke commands didn’t appear.

So I did a sudo -u _snips-skills snips-skill-server -vvv

and it seems like the ‘Hello’ and 'Bye spoken commands are heard (maybe?)

[14:53:15.884316] DEBUG:snips_common_cli::cli: conf lookup: snips-skill-server.skill_dir -> Ok(None)
[14:53:15.884404] DEBUG:snips_common_cli::cli: conf lookup: snips-common.skill_dir -> Ok(None)
[14:53:15.884429] DEBUG:snips_common_cli::cli: conf lookup: snips-skill-server.skills_root_dir -> Ok(None)
[14:53:15.884448] DEBUG:snips_common_cli::cli: conf lookup: snips-common.skills_root_dir -> Ok(None)
[14:53:15.884554] INFO :snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/SirBuildsALot7.Greetings
[14:53:15.884819] INFO :snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py"
[14:53:15.884880] INFO :snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py"
[14:53:15.885578] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][out] thread started
[14:53:15.886383] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] thread started
[14:53:15.886732] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][out] thread started
[14:53:15.893005] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] thread started
^C
[14:54:00.626177] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[14:54:00.626219] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py", line 45, in <module>
[14:54:00.626228] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:hello", subscribe_intent_callback) \
[14:54:00.626242] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
[14:54:00.626290] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]     self.loop_forever()
[14:54:00.626315] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 317, in loop_forever
[14:54:00.626365] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]     sleep(.1)
[14:54:00.626378] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] KeyboardInterrupt
[14:54:00.626396] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[14:54:00.626420] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py", line 45, in <module>
[14:54:00.626467] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:bye", subscribe_intent_callback) \
[14:54:00.626497] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
[14:54:00.626582] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]     self.loop_forever()
[14:54:00.626604] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 317, in loop_forever
[14:54:00.626679] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]     sleep(.1)
[14:54:00.626716] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] KeyboardInterrupt
[14:54:00.633385] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] thread stopped
[14:54:00.633441] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][out] thread stopped
[14:54:00.634100] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][out] thread stopped
[14:54:00.634188] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] thread stopped
[14:54:00.667316] INFO :snips_skill_server_lib::runner: was asked to stop, terminating childs before quitting...
[14:54:00.667366] DEBUG:snips_skill_server_lib::handler::nosandbox: sending kill to SirBuildsALot7-bye-SirBuildsALot7.Greetings
[14:54:00.667396] DEBUG:snips_skill_server_lib::handler::nosandbox: sending kill to SirBuildsALot7-hello-SirBuildsALot7.Greetings
[14:54:00.667414] DEBUG:snips_skill_server_lib::handler::nosandbox: waiting for SirBuildsALot7-bye-SirBuildsALot7.Greetings to stop
[14:54:00.667441] DEBUG:snips_skill_server_lib::handler::nosandbox: SirBuildsALot7-bye-SirBuildsALot7.Greetings has stopped
[14:54:00.667456] DEBUG:snips_skill_server_lib::handler::nosandbox: waiting for SirBuildsALot7-hello-SirBuildsALot7.Greetings to stop
[14:54:00.667477] DEBUG:snips_skill_server_lib::handler::nosandbox: SirBuildsALot7-hello-SirBuildsALot7.Greetings has stopped

I’m so lost

Hello @Hav0ck,

And things hung so I did a CTRL+C and this was the output:

Yes it is normal, the script enter into an infinite waiting loop, expecting events to be caught, executing the callbacks binded to your intent in the script.

and it seems like the ‘Hello’ and 'Bye spoken commands are heard (maybe?)

snips-watch is suppose to show you that.

The intent are correctly detected?

They were until I changed the ownership of the skills directory. They are not now, according to snips-watch

Strange. both are unrelated.

Can you post the output of snips-watch -vvv

Ok,

I’m going to try my best to post as much data from the ‘Hey Snips’ I just tried.
Before I started I did a sudo systemctl restart snips-* on my base unit just to ensure things were fresh.

I’m not sure why but the time stamps for the logs don’t line up but I did only 1 ‘Hey Snips’ followed by ‘Hello’ which is one of two actions for the Greetings assistant by SirBuildALot7.

Snips-Watch -vvv

[17:11:25] Watching on 192.168.1.105:1883 (MQTT)
[17:11:37] [Hotword] detected on site living-room, for model hey_snips
[17:11:37] [Asr] was asked to stop listening on site living-room
[17:11:37] [Hotword] was asked to toggle itself 'off' on site living-room
[17:11:37] [Dialogue] session with id '89b16cd2-a69a-4df4-856c-67bfeb29e2d4' was started on site living-room
[17:11:37] [AudioServer] was asked to play a wav of 41.1 kB with id '99c412ac-e738-4ad4-9e7f-0f357dbcdc89' on site living-room
[17:11:37] [AudioServer] finished playing wav with id '99c412ac-e738-4ad4-9e7f-0f357dbcdc89'
[17:11:37] [Asr] was asked to listen on site living-room
[17:11:53] [Dialogue] session with id '89b16cd2-a69a-4df4-856c-67bfeb29e2d4' was ended on site living-room. The session was ended because one of the component didn't respond in a timely manner
[17:11:53] [Asr] was asked to stop listening on site living-room
[17:11:53] [Hotword] was asked to toggle itself 'on' on site living-room

Output from: sudo -u _snips-skills snips-skill-server -vvv

[17:13:12.921349] DEBUG:snips_common_cli::cli: conf lookup: snips-skill-server.skill_dir -> Ok(None)
[17:13:12.921553] DEBUG:snips_common_cli::cli: conf lookup: snips-common.skill_dir -> Ok(None)
[17:13:12.921651] DEBUG:snips_common_cli::cli: conf lookup: snips-skill-server.skills_root_dir -> Ok(None)
[17:13:12.921743] DEBUG:snips_common_cli::cli: conf lookup: snips-common.skills_root_dir -> Ok(None)
[17:13:12.922018] INFO :snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/SirBuildsALot7.Greetings
[17:13:12.922279] INFO :snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py"
[17:13:12.922423] INFO :snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py"
[17:13:12.923860] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][out] thread started
[17:13:12.923934] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] thread started
[17:13:12.924921] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][out] thread started
[17:13:12.924968] DEBUG:snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] thread started
[17:14:34.192947] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[17:14:34.192984] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py", line 45, in <module>
[17:14:34.192993] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:bye", subscribe_intent_callback) \
[17:14:34.192999] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
[17:14:34.193052] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[17:14:34.193076] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py", line 45, in <module>
[17:14:34.193123] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:hello", subscribe_intent_callback) \
[17:14:34.193154] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
[17:14:34.207917] INFO :snips_skill_server_lib::runner: was asked to stop, terminating childs before quitting...
[17:14:34.207947] DEBUG:snips_skill_server_lib::handler::nosandbox: sending kill to SirBuildsALot7-bye-SirBuildsALot7.Greetings
[17:14:34.207965] DEBUG:snips_skill_server_lib::handler::nosandbox: sending kill to SirBuildsALot7-hello-SirBuildsALot7.Greetings
[17:14:34.208093] DEBUG:snips_skill_server_lib::handler::nosandbox: waiting for SirBuildsALot7-bye-SirBuildsALot7.Greetings to stop
[17:14:34.208119] DEBUG:snips_skill_server_lib::handler::nosandbox: SirBuildsALot7-bye-SirBuildsALot7.Greetings has stopped
[17:14:34.208127] DEBUG:snips_skill_server_lib::handler::nosandbox: waiting for SirBuildsALot7-hello-SirBuildsALot7.Greetings to stop
[17:14:34.208140] DEBUG:snips_skill_server_lib::handler::nosandbox: SirBuildsALot7-hello-SirBuildsALot7.Greetings has stopped

Syslog

May 24 17:11:21 heimdall systemd[1]: Started Snips Injection.
May 24 17:11:21 heimdall systemd[1]: Started Snips ASR.
May 24 17:11:21 heimdall systemd[1]: Started Snips Hotword.
May 24 17:11:21 heimdall systemd[1]: Stopped Snips TTS.
May 24 17:11:21 heimdall systemd[1]: Stopped Snips NLU.
May 24 17:11:21 heimdall systemd[1]: Started Snips NLU.
May 24 17:11:21 heimdall systemd[1]: Started Snips TTS.
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_hermes: Loading the configuration file
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::config: Loading language en
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_hermes     : Starting...
May 24 17:11:21 heimdall systemd[1]: Stopped Snips Skill Server.
May 24 17:11:21 heimdall systemd[1]: Started Snips Skill Server.
May 24 17:11:21 heimdall snips-nlu[16608]: INFO:snips_nlu_hermes: loading nlu engine "/usr/share/snips/assistant/nlu_engine"
May 24 17:11:21 heimdall snips-hotword[16607]: INFO:snips_hotword::server: Using model from /usr/share/snips/assistant/custom_hotword
May 24 17:11:21 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::universal: Loading model "/usr/share/snips/assistant/custom_hotword", hotword_key: Some("hey_snips"), model_id: Some("workflow-hey_snips_subww_feedback_10seeds-2018_12_04T12_13_05_evaluated_model_0002")
May 24 17:11:21 heimdall snips-tts[16609]: INFO:snips_tts_hermes: starting with provider PicoTts
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/SirBuildsALot7.Greetings
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py"
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py"
May 24 17:11:21 heimdall snips-nlu[16608]: INFO:snips_nlu_hermes: model loaded in 273 ms
May 24 17:11:21 heimdall snips-injection[16591]: INFO:snips_injection_hermes: Launch with assistant "/usr/share/snips/assistant" and user dir "/var/lib/snips"
May 24 17:11:21 heimdall snips-asr[16592]: INFO:snips_asr: Connecting using MQTT site-id base
May 24 17:11:21 heimdall snips-asr[16592]: INFO:snips_asr_hermes: Using model from "/usr/share/snips/assistant/custom_asr"
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_kaldi::decode::model: Loading model v2
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_asr_hermes          : Preparing decoder
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_asr_hermes          : Idle
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword::server       : Discovered audio_server living-room/mqtt, starting hotword listener
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword::server       : Connecting using MQTT site-id living-room
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for living-room started
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Net and VAD thread for site living-room started (vad inhibitor: true, vad messages: false
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib           : Detector "detector.hey_snips.living-room", sensitivity: 0.5, threshold 0.787
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib           : detector.hey_snips.living-room thread started
May 24 17:11:37 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [living-room]: reset site -> turn off ASR and turn off Hotword
May 24 17:11:37 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [89b16cd2-a69a-4df4-856c-67bfeb29e2d4]: The session has been created because the site "living-room" is free
May 24 17:11:37 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for site living-room stopped.
May 24 17:11:37 heimdall snips-tts[16609]: INFO:snips_tts_hermes: Received a play finished message from audio server with an unknown id '99c412ac-e738-4ad4-9e7f-0f357dbcdc89'. Ignoring it.
May 24 17:11:53 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [89b16cd2-a69a-4df4-856c-67bfeb29e2d4]: closing session which was active: Timeout
May 24 17:11:53 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [living-room]: reset site -> turn off ASR and turn on Hotword
May 24 17:11:53 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for site living-room started.

Output from: sudo systemctl status snips-*

● snips-dialogue.service - Snips Dialogue
   Loaded: loaded (/lib/systemd/system/snips-dialogue.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16588 (snips-dialogue)
    Tasks: 4 (limit: 2320)
   CGroup: /system.slice/snips-dialogue.service
           └─16588 /usr/bin/snips-dialogue

May 24 17:11:21 heimdall systemd[1]: Started Snips Dialogue.
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_hermes: Loading the configuration file
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::config: Loading language en
May 24 17:11:21 heimdall snips-dialogue[16588]: INFO:snips_dialogue_hermes     : Starting...
May 24 17:11:37 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [living-room]: reset site -> turn off ASR and turn off Hotword
May 24 17:11:37 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [89b16cd2-a69a-4df4-856c-67bfeb29e2d4]: The session has been created because the site "living-room" is free
May 24 17:11:53 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [89b16cd2-a69a-4df4-856c-67bfeb29e2d4]: closing session which was active: Timeout
May 24 17:11:53 heimdall snips-dialogue[16588]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [living-room]: reset site -> turn off ASR and turn on Hotword

● snips-skill-server.service - Snips Skill Server
   Loaded: loaded (/lib/systemd/system/snips-skill-server.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16625 (snips-skill-ser)
    Tasks: 12 (limit: 2320)
   CGroup: /system.slice/snips-skill-server.service
           ├─16625 /usr/bin/snips-skill-server
           ├─16649 sh -c . venv/bin/activate && ./action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py
           ├─16653 sh -c . venv/bin/activate && ./action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py
           ├─16660 python2 ./action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py
           └─16661 python2 ./action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py

May 24 17:11:21 heimdall systemd[1]: Stopped Snips Skill Server.
May 24 17:11:21 heimdall systemd[1]: Started Snips Skill Server.
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/SirBuildsALot7.Greetings
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py"
May 24 17:11:21 heimdall snips-skill-server[16625]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/SirBuildsALot7.Greetings/action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py"

● snips-tts.service - Snips TTS
   Loaded: loaded (/lib/systemd/system/snips-tts.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16609 (snips-tts)
    Tasks: 2 (limit: 2320)
   CGroup: /system.slice/snips-tts.service
           └─16609 /usr/bin/snips-tts

May 24 17:11:21 heimdall systemd[1]: Stopped Snips TTS.
May 24 17:11:21 heimdall systemd[1]: Started Snips TTS.
May 24 17:11:21 heimdall snips-tts[16609]: INFO:snips_tts_hermes: starting with provider PicoTts
May 24 17:11:37 heimdall snips-tts[16609]: INFO:snips_tts_hermes: Received a play finished message from audio server with an unknown id '99c412ac-e738-4ad4-9e7f-0f357dbcdc89'. Ignoring it.

● snips-hotword.service - Snips Hotword
   Loaded: loaded (/lib/systemd/system/snips-hotword.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16607 (snips-hotword)
    Tasks: 5 (limit: 2320)
   CGroup: /system.slice/snips-hotword.service
           └─16607 /usr/bin/snips-hotword

May 24 17:11:21 heimdall snips-hotword[16607]: INFO:snips_hotword::server: Using model from /usr/share/snips/assistant/custom_hotword
May 24 17:11:21 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::universal: Loading model "/usr/share/snips/assistant/custom_hotword", hotword_key: Some("hey_snips"), model_id: Some("workflow-hey_snips_subww_feedback_10seeds-2018_12_04T12_13_05_evaluated_model_id: Some("workflow-hey_snips_subww_feedback_10seeds-2018_12_04T12_13_05_evaluated_model_0002")
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword::server       : Discovered audio_server living-room/mqtt, starting hotword listener
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword::server       : Connecting using MQTT site-id living-room
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for living-room started
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Net and VAD thread for site living-room started (vad inhibitor: true, vad messages: false
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib           : Detector "detector.hey_snips.living-room", sensitivity: 0.5, threshold 0.787
May 24 17:11:22 heimdall snips-hotword[16607]: INFO:snips_hotword_lib           : detector.hey_snips.living-room thread started
May 24 17:11:37 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for site living-room stopped.
May 24 17:11:53 heimdall snips-hotword[16607]: INFO:snips_hotword_lib::audio    : Audio thread for site living-room started.

● snips-nlu.service - Snips NLU
   Loaded: loaded (/lib/systemd/system/snips-nlu.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16608 (snips-nlu)
    Tasks: 3 (limit: 2320)
   CGroup: /system.slice/snips-nlu.service
           └─16608 /usr/bin/snips-nlu

May 24 17:11:21 heimdall systemd[1]: Stopped Snips NLU.
May 24 17:11:21 heimdall systemd[1]: Started Snips NLU.
May 24 17:11:21 heimdall snips-nlu[16608]: INFO:snips_nlu_hermes: loading nlu engine "/usr/share/snips/assistant/nlu_engine"
May 24 17:11:21 heimdall snips-nlu[16608]: INFO:snips_nlu_hermes: model loaded in 273 ms

● snips-injection.service - Snips Injection
   Loaded: loaded (/lib/systemd/system/snips-injection.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16591 (snips-injection)
    Tasks: 3 (limit: 2320)
   CGroup: /system.slice/snips-injection.service
           └─16591 /usr/bin/snips-injection

May 24 17:11:21 heimdall systemd[1]: Stopping Snips Injection...
May 24 17:11:21 heimdall systemd[1]: Stopped Snips Injection.
May 24 17:11:21 heimdall systemd[1]: Started Snips Injection.
May 24 17:11:21 heimdall snips-injection[16591]: INFO:snips_injection_hermes: Launch with assistant "/usr/share/snips/assistant" and user dir "/var/lib/snips"

● snips-asr.service - Snips ASR
   Loaded: loaded (/lib/systemd/system/snips-asr.service; enabled; vendor preset: enabled)
   Active: active (running) since Fri 2019-05-24 17:11:21 UTC; 16min ago
 Main PID: 16592 (snips-asr)
    Tasks: 5 (limit: 2320)
   CGroup: /system.slice/snips-asr.service
           └─16592 /usr/bin/snips-asr

May 24 17:11:21 heimdall systemd[1]: Stopped Snips ASR.
May 24 17:11:21 heimdall systemd[1]: Started Snips ASR.
May 24 17:11:21 heimdall snips-asr[16592]: INFO:snips_asr: Connecting using MQTT site-id base
May 24 17:11:21 heimdall snips-asr[16592]: INFO:snips_asr_hermes: Using model from "/usr/share/snips/assistant/custom_asr"
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_kaldi::decode::model: Loading model v2
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_asr_hermes          : Preparing decoder
May 24 17:11:22 heimdall snips-asr[16592]: INFO:snips_asr_hermes          : Idle

If there is anything else I can post please let me know.

According to snips-watch you didn’t say anything after the hey snips. right?

I don’t see any intent detected.

Also your action code seems to produce some exceptions.

[17:14:34.192947] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[17:14:34.192984] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-bye-SirBuildsALot7.Greetings.py", line 45, in <module>
[17:14:34.192993] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:bye", subscribe_intent_callback) \
[17:14:34.192999] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-bye-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start
[17:14:34.193052] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err] Traceback (most recent call last):
[17:14:34.193076] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "./action-SirBuildsALot7-hello-SirBuildsALot7.Greetings.py", line 45, in <module>
[17:14:34.193123] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]     h.subscribe_intent("SirBuildsALot7:hello", subscribe_intent_callback) \
[17:14:34.193154] INFO :snips_skill_server_lib::runner: [SirBuildsALot7-hello-SirBuildsALot7.Greetings][err]   File "/var/lib/snips/skills/SirBuildsALot7.Greetings/venv/local/lib/python2.7/site-packages/hermes_python/hermes.py", line 307, in start

try running them directly in your terminal (in their virtualenv) to check the error.

The intent (I was calling them commands) were spoken but not detected. They were detected but after I chowned the .var/lib/snips/skills/ directory the intents were not detected.
At the time I thought this was related but now I don’t know.

I see that but I have no idea what they mean or how to address them.

So I am also now getting no Hotword detection. We had a storm roll through the area on the weekend. While everything is working (as far as the Pi Zero and VM) I am not able to get the Hotword detected.

Edit: I got the hotword detection working. I had the snips-skill-server running on the Pi but it was in a failed state and blocking the Hotword detection. I stopped the service and the Hotword detection restarted.
Still no recognition of the spoken Intent though