[SOLVED] Snips NLU not running

Snips stopped parsing intends even after i did sam update:

[23:25:06] [Nlu] was asked to parse input "led an"
[23:25:21] [Dialogue] session with id ‘e753ea9e-e285-404f-96f7-de3315f3b93e’ was ended on site default. The session was ended because one of the component didn’t respond in a timely manner

[23:24:59] Watching on localhost:1883 (MQTT)
[23:25:01] [Hotword] detected on site default, for model default
[23:25:01] [Asr] was asked to stop listening on site default
[23:25:01] [Hotword] was asked to toggle itself 'off' on site default
[23:25:01] [Dialogue] session with id 'e753ea9e-e285-404f-96f7-de3315f3b93e' was started on site default
[23:25:01] [AudioServer] was asked to play a wav of 41.1 kB with id '331acbfa-a4b8-4a75-92e4-950a5efeb4a9' on site default
[23:25:02] [AudioServer] finished playing wav with id '331acbfa-a4b8-4a75-92e4-950a5efeb4a9'
[23:25:02] [Asr] was asked to listen on site default
[23:25:04] [Asr] captured text "led an" in 2.0s
[23:25:04] [Asr] was asked to stop listening on site default
[23:25:04] [AudioServer] was asked to play a wav of 93.1 kB with id 'a4fad23d-01c8-42a8-adf4-f1f6336ffbe4' on site default
[23:25:06] [AudioServer] finished playing wav with id 'a4fad23d-01c8-42a8-adf4-f1f6336ffbe4'
[23:25:06] [Nlu] was asked to parse input "led an"
[23:25:21] [Dialogue] session with id 'e753ea9e-e285-404f-96f7-de3315f3b93e' was ended on site default. The session was ended because one of the component didn't respond in a timely manner
[23:25:21] [Asr] was asked to stop listening on site default
[23:25:21] [Hotword] was asked to toggle itself 'on' on site default
[23:26:07] [Hotword] detected on site default, for model default
[23:26:07] [Asr] was asked to stop listening on site default
[23:26:07] [Hotword] was asked to toggle itself 'off' on site default
[23:26:07] [Dialogue] session with id 'dce93afd-9a7d-44ed-a478-5a3070db1c30' was started on site default
[23:26:07] [AudioServer] was asked to play a wav of 41.1 kB with id '8ab72602-bbc8-4fa2-94b7-9920690c50be' on site default
[23:26:08] [AudioServer] finished playing wav with id '8ab72602-bbc8-4fa2-94b7-9920690c50be'
[23:26:08] [Asr] was asked to listen on site default
[23:26:10] [Asr] captured text "ein witz bitte" in 2.0s
[23:26:10] [Asr] was asked to stop listening on site default
[23:26:10] [AudioServer] was asked to play a wav of 93.1 kB with id '957abd34-002c-4129-9a91-2e5b541c99b3' on site default
[23:26:11] [AudioServer] finished playing wav with id '957abd34-002c-4129-9a91-2e5b541c99b3'
[23:26:11] [Nlu] was asked to parse input "ein witz bitte"
[23:26:27] [Dialogue] session with id 'dce93afd-9a7d-44ed-a478-5a3070db1c30' was ended on site default. The session was ended because one of the component didn't respond in a timely manner
[23:26:27] [Asr] was asked to stop listening on site default
[23:26:27] [Hotword] was asked to toggle itself 'on' on site default


OS version ................... Raspbian GNU/Linux 9 (stretch)
Installed assistant .......... Jarvis_de
Language ..................... de
Hotword ...................... hey_snips
ASR engine ................... snips
Status ....................... Live

Service status:

snips-asr .................... 0.63.3 (running)
snips-audio-server ........... 0.63.3 (running)
snips-dialogue ............... 0.63.3 (running)
snips-hotword ................ 0.63.3 (running)
snips-nlu .................... 0.63.3 (not running)
snips-skill-server ........... 0.63.3 (running)
snips-tts .................... 0.63.3 (running)
pi@himbipi:~ $ sudo -pi snips-nlu -vv
[23:18:30.037773] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.assistant -> Ok(None)
[23:18:30.038164] DEBUG:snips_common_cli::cli: conf lookup: snips-common.assistant -> Ok(None)
[23:18:30.038397] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.model -> Ok(None)
[23:18:30.038476] DEBUG:snips_common_cli::cli: conf lookup: snips-common.model -> Ok(None)
[23:18:30.038560] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.user_dir -> Ok(None)
[23:18:30.038640] DEBUG:snips_common_cli::cli: conf lookup: snips-common.user_dir -> Ok(None)
[23:18:30.038717] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.bus -> Ok(None)
[23:18:30.038790] DEBUG:snips_common_cli::cli: conf lookup: snips-common.bus -> Ok(None)
[23:18:30.038870] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.mqtt -> Ok(None)
[23:18:30.038944] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt -> Ok(None)
[23:18:30.039137] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.mqtt_username -> Ok(None)
[23:18:30.039221] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_username -> Ok(None)
[23:18:30.039304] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.mqtt_password -> Ok(None)
[23:18:30.039379] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_password -> Ok(None)
[23:18:30.039459] DEBUG:snips_common_cli::cli: conf lookup: snips-nlu.mqtt_tls_hostname -> Ok(None)
[23:18:30.039542] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_tls_hostname -> Ok(None)
[23:18:30.039743] DEBUG:rumqtt::client       : snips-nlu|1152-himbipi-1: Client start
[23:18:30.039822] INFO :rumqtt::connection   : snips-nlu|1152-himbipi-1: Connection start
[23:18:30.039890] DEBUG:rumqtt::connection   : snips-nlu|1152-himbipi-1 new connection
[23:18:30.042395] DEBUG:rumqtt::connection   : Send: Connect(Connect { protocol: MQTT(4), keep_alive: 10, client_id: "snips-nlu|1152-himbipi-1", clean_session: false, last_will: None, username: None, password: None })
[23:18:30.049842] DEBUG:rumqtt::connection   : snips-nlu|1152-himbipi-1 Received control message Connack(Connack { session_present: false, code: Accepted })
[23:18:30.050042] INFO :rumqtt::connection   : snips-nlu|1152-himbipi-1: Connection established
[23:18:30.050110] DEBUG:rumqtt::client       : snips-nlu|1152-himbipi-1: Spawning client thread
[23:18:30.050751] DEBUG:rumqtt::client       : snips-nlu|1152-himbipi-1: Entering normal operation loop
[23:18:30.050794] DEBUG:snips_injection_lib::injectable: computing injections dirname from reference dir "/usr/share/snips/assistant"
[23:18:30.050918] DEBUG:rumqtt::connection             : Send: Subscribe(Subscribe { pid: PacketIdentifier(1), topics: [SubscribeTopic { topic_path: "hermes/nlu/query", qos: AtMostOnce }] })
[23:18:30.051067] DEBUG:snips_injection_lib::injectable: filtering entry Ok(DirEntry("/var/lib/snips/injections/20191123T220035092284736/inj_20191123T220648413409312"))
[23:18:30.051140] DEBUG:rumqtt::connection             : Send: Subscribe(Subscribe { pid: PacketIdentifier(2), topics: [SubscribeTopic { topic_path: "hermes/nlu/partialQuery", qos: AtMostOnce }] })
[23:18:30.051270] DEBUG:rumqtt::connection             : Send: Subscribe(Subscribe { pid: PacketIdentifier(3), topics: [SubscribeTopic { topic_path: "hermes/nlu/versionRequest", qos: AtMostOnce }] })
[23:18:30.051275] DEBUG:snips_injection_lib::injectable: sorting injection: Injection { root_dir: "/var/lib/snips/injections/20191123T220035092284736/inj_20191123T220648413409312", date: 2019-11-23T22:06:48.413409312Z }
[23:18:30.051380] DEBUG:rumqtt::connection             : Send: Subscribe(Subscribe { pid: PacketIdentifier(4), topics: [SubscribeTopic { topic_path: "hermes/nlu/reload", qos: AtMostOnce }] })
[23:18:30.051402] DEBUG:snips_injection_lib::injectable: filtering entry Ok(DirEntry("/var/lib/snips/injections/20191123T220035092284736/inj_20191123T221556237057026"))
[23:18:30.051583] DEBUG:snips_injection_lib::injectable: sorting injection: Injection { root_dir: "/var/lib/snips/injections/20191123T220035092284736/inj_20191123T221556237057026", date: 2019-11-23T22:15:56.237057026Z }
[23:18:30.051729] DEBUG:rumqtt::connection             : snips-nlu|1152-himbipi-1 Received control message Suback(Suback { pid: PacketIdentifier(1), return_codes: [Success(AtMostOnce)] })
[23:18:30.051773] INFO :snips_nlu_hermes               : loading nlu engine "/var/lib/snips/injections/20191123T220035092284736/inj_20191123T221556237057026/nlu_engine"
[23:18:30.052092] DEBUG:rumqtt::connection             : snips-nlu|1152-himbipi-1 Received control message Suback(Suback { pid: PacketIdentifier(2), return_codes: [Success(AtMostOnce)] })
[23:18:30.084097] ERROR:snips_nlu                      : Unable to read file '/var/lib/snips/injections/20191123T220035092284736/inj_20191123T221556237057026/nlu_engine/nlu_engine.json'
 -> caused by: Mismatched model version: model is 0.20.0 but runner is 0.19.0

[23:18:30.084121] ERROR:rumqtt::client                 : snips-nlu|1152-himbipi-1 Disconnected: (Error(SyncMpsc(Disconnected), State { next_error: None, backtrace: None }))
[23:18:30.084462] DEBUG:rumqtt::client                 : snips-nlu|1152-himbipi-1: Entering reconnecting loop
[23:18:30.084528] INFO :rumqtt::client                 : not seeking reconnection
[23:18:30.084594] INFO :rumqtt::client                 : client thread done

-> caused by: Mismatched model version: model is 0.20.0 but runner is 0.19.0

Does anyone has an idea to fix this?

Snips-NLU is working fine… its the skill that did not do anything in a timely manner or ever at all, which is why you got the message.

this is because your console assistant is newer than the snips version you are running.
Snips current version is 0.64.0, and you are running 0.63.3

update snips to the latest version to solve that problem

sam update didn’t work and the manual update

sudo su
apt-get update && apt-get upgrade snips-platform-voice

failed:

W: Failed to fetch https://raspbian.snips.ai/stretch/dists/stable/InRelease The following signatures couldn't be verified because the public key is not available: NO_PUBKEY 531DD1A7B702B14D

I managed to get it working by:

Just in case someone else runs into this issue :slight_smile: