search

snipsco / snips-issues

Feel free to share your bugs with us.
14 stars 5 forks source link

[Platform][snips-hotword] Missing erroneous path in log when a file is not found. #158

Open Psychokiller1888 opened 5 years ago

Psychokiller1888 commented 5 years ago

Yes, I'm still on stretch, on a pi 3b+, I have more problems on the pi 4 with buster

The hotword keeps failing with

pi@ProjectAliceDev:~/ProjectAlice $ snips-hotword -vvvvvvvvvvvvvvv
[15:39:38.326186] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.bus -> Ok(None)
[15:39:38.326713] DEBUG:snips_common_cli::cli: conf lookup: snips-common.bus -> Ok(None)
[15:39:38.326817] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.mqtt -> Ok(None)
[15:39:38.326895] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt -> Ok(None)
[15:39:38.327103] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.mqtt_username -> Ok(None)
[15:39:38.327192] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_username -> Ok(None)
[15:39:38.327278] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.mqtt_password -> Ok(None)
[15:39:38.327359] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_password -> Ok(None)
[15:39:38.327446] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.mqtt_tls_hostname -> Ok(None)
[15:39:38.327530] DEBUG:snips_common_cli::cli: conf lookup: snips-common.mqtt_tls_hostname -> Ok(None)
[15:39:38.327726] DEBUG:rumqtt::client       : snips-hotword|3663-ProjectAliceDev-1: Client start
[15:39:38.327807] INFO :rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1: Connection start
[15:39:38.327886] DEBUG:rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1 new connection
[15:39:38.330319] DEBUG:rumqtt::connection   : Send: Connect(Connect { protocol: MQTT(4), keep_alive: 10, client_id: "snips-hotword|3663-ProjectAliceDev-1", clean_session: false, last_will: None, username: None, password: None })
[15:39:38.330922] DEBUG:rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1 Received control message Connack(Connack { session_present: false, code: Accepted })
[15:39:38.331114] INFO :rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1: Connection established
[15:39:38.331179] DEBUG:rumqtt::client       : snips-hotword|3663-ProjectAliceDev-1: Spawning client thread
[15:39:38.331603] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.hotword_id -> Ok(None)
[15:39:38.331748] DEBUG:snips_common_cli::cli: conf lookup: snips-common.hotword_id -> Ok(None)
[15:39:38.331811] DEBUG:rumqtt::client       : snips-hotword|3663-ProjectAliceDev-1: Entering normal operation loop
[15:39:38.332100] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.sensitivity -> Ok(None)
[15:39:38.332125] DEBUG:rumqtt::connection   : Send: Subscribe(Subscribe { pid: PacketIdentifier(1), topics: [SubscribeTopic { topic_path: "hermes/hotword/toggleOn", qos: AtMostOnce }] })
[15:39:38.332194] DEBUG:snips_common_cli::cli: conf lookup: snips-common.sensitivity -> Ok(None)
[15:39:38.332289] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.model -> Ok(None)
[15:39:38.332370] DEBUG:snips_common_cli::cli: conf lookup: snips-common.model -> Ok(None)
[15:39:38.332459] DEBUG:snips_common_cli::cli: conf lookup: snips-hotword.assistant -> Ok(None)
[15:39:38.332515] DEBUG:rumqtt::connection   : Send: Subscribe(Subscribe { pid: PacketIdentifier(2), topics: [SubscribeTopic { topic_path: "hermes/hotword/toggleOff", qos: AtMostOnce }] })
[15:39:38.332540] DEBUG:snips_common_cli::cli: conf lookup: snips-common.assistant -> Ok(Some("/home/pi/ProjectAlice/assistant"))
[15:39:38.332837] DEBUG:rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1 Received control message Suback(Suback { pid: PacketIdentifier(1), return_codes: [Success(AtMostOnce)] })
[15:39:38.333179] DEBUG:rumqtt::connection   : snips-hotword|3663-ProjectAliceDev-1 Received control message Suback(Suback { pid: PacketIdentifier(2), return_codes: [Success(AtMostOnce)] })
[15:39:38.333232] ERROR:snips_hotword        : No such file or directory (os error 2)

[15:39:38.333411] ERROR:rumqtt::client       : snips-hotword|3663-ProjectAliceDev-1 Disconnected: (Error(SyncMpsc(Disconnected), State { next_error: None, backtrace: None }))
[15:39:38.333543] DEBUG:rumqtt::client       : snips-hotword|3663-ProjectAliceDev-1: Entering reconnecting loop
[15:39:38.333634] INFO :rumqtt::client       : not seeking reconnection

No such file or directory.... Would be nice to know what it is missing?

cpoisson commented 5 years ago

That's a lot '-vvvvvvvv' :)

Sure, we have improvement on going related to giving more useful debug information.

It is looking for the hotword subfolder in "/home/pi/ProjectAlice/assistant"

Psychokiller1888 commented 5 years ago

Apparently not, becaues the folder is valid. Somethng else is wrong

Psychokiller1888 commented 5 years ago

https://puu.sh/DLw7B.png

cpoisson commented 5 years ago

Weird, I'm doing some test, it seems that the first file loaded is config.json, then the model.pb in the same subfolder.

e.g. the default hey_snips is shipped with 3 files:

pi@snips-home-base:~ $ ls /usr/share/snips/assistant/custom_hotword/
config.json  model.pb  thresholds_curve.json

Do you have them all?

Psychokiller1888 commented 5 years ago

Yep, got them all

Psychokiller1888 commented 5 years ago

Tbh, bored not finding a solution since yesterday (been trying almost the entire today), I just flashed a working image. Anyway, close this ticket, but please, make the logs tell us what's missing :)

cpoisson commented 5 years ago

yeah, I do agree. I need them for debug too :)

Psychokiller1888 commented 5 years ago

@cpoisson

No idea what this one is missing....

Jun 29 20:48:08 ProjectAliceDev snips-asr[1738]: INFO:snips_asr_lib::asr        : decoded: [Recognition { decoded_string: "what time is it", likelihood: 0.95658696, tokens: Some([Token { value: "what", confidence: 1.0, time: (0.0, 1.1999999), range: 0..4 }, Token { value: "time", confidence: 1.0, time: (1.1999999, 1.5899999), range: 5..9 }, Token { value: "is", confidence: 1.0, time: (1.5899999, 1.7099999), range: 10..12 }, Token { value: "it", confidence: 0.8373322, time: (1.7099999, 2.52), range: 13..15 }]) }]
Jun 29 20:48:08 ProjectAliceDev snips-asr[1738]: INFO:snips_asr_hermes::handler : Publishing the recognition
Jun 29 20:48:08 ProjectAliceDev snips-asr[1738]: INFO:snips_asr_hermes::handler : Preparing decoder
Jun 29 20:48:08 ProjectAliceDev snips-dialogue[1753]: ERROR:snips_dialogue_hermes::run_handler          : Executor: No such file or directory (os error 2)
Jun 29 20:48:13 ProjectAliceDev systemd[1]: snips-dialogue.service: Service hold-off time over, scheduling restart.
Jun 29 20:48:13 ProjectAliceDev systemd[1]: Stopped Snips Dialogue.
Jun 29 20:48:13 ProjectAliceDev systemd[1]: Started Snips Dialogue.
Jun 29 20:48:13 ProjectAliceDev snips-dialogue[1765]: INFO:snips_dialogue_hermes: Loading the configuration file
Jun 29 20:48:13 ProjectAliceDev snips-dialogue[1765]: INFO:snips_dialogue_lib::config: Loading language en