search

Alpha200 / snips-openhab

Skill für Snips.ai zur Ansteuerung von Geräten mit OpenHAB
Apache License 2.0
2 stars 4 forks source link

.items Anfänger #13

Open markfromarch opened 5 years ago

markfromarch commented 5 years ago

Hallo zusammen,

als OpenHAB Anfänger schaffe ich es leider nicht, den Skill zu Luafne zu bringen.

Sam watch gibt folgendes aus: ... [Asr] captured text "setze die wiedergabe im wohnzimmer fort" in 3.0s [11:14:16] [Asr] was asked to stop listening on site default [11:14:16] [AudioServer] was asked to play a wav of 93.1 kB with id '9f325e70-0ae5-498e-aefd-e7b4a3dfc678' on site default [11:14:17] [AudioServer] finished playing wav with id '9f325e70-0ae5-498e-aefd-e7b4a3dfc678' [11:14:17] [Nlu] was asked to parse input "setze die wiedergabe im wohnzimmer fort" [11:14:17] [Nlu] detected intent Alpha200:playMedia with confidence score 1.000 for input "setze die wiedergabe im wohnzimmer fort" Slots -> room -> Wohnzimmer (confidence: 1.000) [11:14:17] [Dialogue] New intent detected Alpha200:playMedia with confidence 1.000 Slots -> room -> Wohnzimmer (confidence: 1.000) [11:14:23] [Dialogue] session with id 'ec42f502-3673-43f6-912f-85576e846ea5' was ended on site default. The session was ended because one of the component didn't respond in a timely manner [11:14:23] [Asr] was asked to stop listening on site default [11:14:23] [Hotword] was asked to toggle itself 'on' on site default ... Meine OpenHAB Itemes Datei: Group gFF "2. Etage" ["FirstFloor"] Group gGF "1. Etage" ["GroundFloor"] Group gC "Keller" ["Basement"] Group Garden "Balkon" ["Garden"]

Group Wohnzimmer "Wohnzimmer"

Group FF_Bath "Badezimmer" (gFF) ["Badezimmer"] Group FF_Corridor "Flur" (gFF) ["Flur"]

/ active groups / Group Lautsprecher "Alle Lautsprecher" ["Wohnzimmer","room","Raum"]

/ Speakers /

Player Sonos_Controller "Controller" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:control"} Dimmer Sonos_Volume "Volume [%.1f %%]" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:volume"} Switch Sonos_Mute "Mute" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:mute"} Switch Sonos_LED "LED" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:led"} String Sonos_CurrentTrack "Now playing [%s]" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:currenttrack"} String Sonos_State "Status [%s]" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:state"} Switch Sonos_PlayQueue "Play Queue" (Wohnzimmer, Lautsprecher) ["Sonos"] {channel="sonos:PLAY1:Wohnzimmer:playqueue"}

// vim: syntax=Xtend

Wo liegt mein Fehler? Danke und Gruß. Mark

Alpha200 commented 5 years ago

Hi markfromarch, da der Skill gar nicht antwortet sitzt das Problem etwas tiefer. Verbinde dich am besten über zwei SSH-Sitzungen mit dem Pi und führe auf der ersten Sitzung "sudo journalctl -f -u snips-skill-server" aus und danach auf der zweiten Sitzung "sudo systemctl restart snips-skill-server" aus. Du solltest dann in der ersten Sitzung eine Fehlerausgabe bezüglich des OpenHAB-Skills sehen.

markfromarch commented 5 years ago

Hallo Alpha, da kommt überraschend wenig raus...

Jul 10 12:42:06 snips snips-skill-server[1723]: INFO:snips_skill_server_lib::runner: was asked to stop, terminating childs before quitting... Jul 10 12:42:06 snips systemd[1]: Stopped Snips Skill Server. Jul 10 12:42:06 snips systemd[1]: Started Snips Skill Server. Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/snips-skill-respeaker Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/snips-skill-respeaker/action-led_animation_1.6.3" Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/snips-openhab Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/snips-openhab/action-openHAB.py" Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: searching dir for actions: /var/lib/snips/skills/snips-app-assistant-information Jul 10 12:42:06 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: found action "/var/lib/snips/skills/snips-app-assistant-information/action-assistant-information.py" Jul 10 12:42:21 snips snips-skill-server[2210]: INFO:snips_skill_server_lib::runner: [openHAB][out] yoooo: <hermes_python.ontology.injection.AddFromVanillaInjectionRequest object at 0x75a075b0>

Alpha200 commented 5 years ago

Kannst du noch einmal gucken, ob ein Fehler geloggt wird, wenn du den Sprachbefehl gibst? Das oben sieht so alles normal aus.

markfromarch commented 5 years ago

sam service log:

Jul 10 15:43:41 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:43:41 snips snips-nlu[464]: INFO:snips_nlu_hermes : model loaded in 1560 ms Jul 10 15:44:10 snips snips-hotword[465]: INFO:snips_hotword_hermes : Hotword detected: site_id:default model:hey_snips (136ms ago) Jul 10 15:44:10 snips snips-dialogue[477]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn off Hotword Jul 10 15:44:10 snips snips-dialogue[477]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [92f4f46f-ae90-48ae-b1ee-ce591e812065]: The session has been created because the site "default" is free Jul 10 15:44:10 snips snips-hotword[465]: INFO:snips_hotword_lib::audio : Audio thread for site default stopped. Jul 10 15:44:10 snips snips-audio-server[475]: INFO:snips_audio_portaudio : Playing "642e336f-6bb4-4c8a-a9e7-6ac4eab1bc52" using output "default", wav spec: WavSpec { channels: 2, sample_rate: 22050, bits_per_sample: 16, sample_format: Int } Jul 10 15:44:11 snips snips-audio-server[475]: INFO:snips_audio_portaudio : Playing of "642e336f-6bb4-4c8a-a9e7-6ac4eab1bc52" finished Jul 10 15:44:11 snips snips-tts[471]: INFO:snips_tts_hermes : Received a play finished message from audio server with an unknown id '642e336f-6bb4-4c8a-a9e7-6ac4eab1bc52'. Ignoring it. Jul 10 15:44:11 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Listening at site id default Jul 10 15:44:11 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Listening Jul 10 15:44:11 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0 entered AsrRunner::run Jul 10 15:44:11 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0+0.000 capture started Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0+3.260 endpoint detected (rule:4) frame:195 samples:49920 signal_time:3.12 rtf:0.467 Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : Source thread stop on push: "Disconnected(..)" Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0+3.266 capture ended Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0+3.270 decoder finalized Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : T0+3.281 lookup and post-processing done Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_lib::asr : decoded: [Recognition { decoded_string: "setze wiedergabe im wohnzimmer fort", likelihood: 0.96284854, tokens: Some([Token { value: "setze", confidence: 1.0, time: (0.0, 0.48), range: 0..5 }, Token { value: "wiedergabe", confidence: 0.8275417, time: (0.48, 1.1999999), range: 6..16 }, Token { value: "im", confidence: 1.0, time: (1.1999999, 1.38), range: 17..19 }, Token { value: "wohnzimmer", confidence: 1.0, time: (1.38, 1.9499999), range: 20..30 }, Token { value: "fort", confidence: 1.0, time: (1.9499999, 2.9399998), range: 31..35 }]) }] Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Publishing the recognition Jul 10 15:44:14 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Preparing decoder Jul 10 15:44:14 snips snips-audio-server[475]: INFO:snips_audio_portaudio : Playing "c7c3c3ab-a57d-4528-9c96-310bf1b671e9" using output "default", wav spec: WavSpec { channels: 2, sample_rate: 22050, bits_per_sample: 16, sample_format: Int } Jul 10 15:44:15 snips snips-audio-server[475]: INFO:snips_audio_portaudio : Playing of "c7c3c3ab-a57d-4528-9c96-310bf1b671e9" finished Jul 10 15:44:15 snips snips-tts[471]: INFO:snips_tts_hermes : Received a play finished message from audio server with an unknown id 'c7c3c3ab-a57d-4528-9c96-310bf1b671e9'. Ignoring it. Jul 10 15:44:21 snips snips-dialogue[477]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [92f4f46f-ae90-48ae-b1ee-ce591e812065]: closing session which was active: Timeout Jul 10 15:44:21 snips snips-dialogue[477]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn on Hotword Jul 10 15:44:21 snips snips-hotword[465]: INFO:snips_hotword_lib::audio : Audio thread for site default started. Jul 10 15:44:23 snips snips-injection[476]: INFO:snips_asr_injection : ASR Injections started Jul 10 15:44:23 snips snips-injection[476]: INFO:snips_asr_injection::core : Number of new words: 0 Jul 10 15:44:23 snips snips-injection[476]: INFO:snips_asr_injection : prepare_injections done in 0.007s. Jul 10 15:44:23 snips snips-injection[476]: INFO:snips_asr_injection : Generating missing words pronunciations Jul 10 15:44:23 snips snips-tts[471]: WARN:snips_tts_hermes::sounds: A sound with id success was already existing, overwriting it. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection : g2p done in 4.084s. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : Updating pronunciations' graph Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : pronunciations' graph done in 0.003s. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : Updating entities' graph Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : Entity room done in 0.186s. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : Entity device done in 0.004s. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::model_version_2 : entities' graph done in 0.195s. Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection::core : Injections performed with success Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_asr_injection : ASR injections done Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_nlu_lib::injection::injection : Starting injection... Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_nlu_lib::injection::injection : Retrieving parsers paths... Jul 10 15:44:27 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Loading stemmer ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301.1v2RSxeQOT87/nlu_engine/resources/de/stemming/stems.txt") ... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Stemmer loaded Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Loading gazetteer 'top_200000_words_stemmed' ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301.1v2RSxeQOT87/nlu_engine/resources/de/gazetteers/top_200000_words_stemmed.txt") ... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Gazetteer 'top_200000_words_stemmed' loaded Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Loading stop words ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301.1v2RSxeQOT87/nlu_engine/resources/de/stop_words.txt") ... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::resources::loading : Stop words loaded Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::entity_parser::builtin_entity_parser: Loading builtin entity parser ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301.1v2RSxeQOT87/nlu_engine/builtin_entity_parser") ... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::entity_parser::builtin_entity_parser: Builtin entity parser loaded Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::entity_parser::custom_entity_parser : Loading custom entity parser ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301.1v2RSxeQOT87/nlu_engine/custom_entity_parser") ... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::entity_parser::custom_entity_parser : Custom entity parser loaded Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::injection::injection : Normalizing injected values... Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::injection::injection : Injecting values for entity 'room' Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_nlu_lib::injection::injection : Injection performed with success ! Jul 10 15:44:28 snips snips-injection[476]: INFO:snips_injection_lib::prepare : injection succeed Jul 10 15:44:28 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Loading model Jul 10 15:44:28 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Loading model Jul 10 15:44:28 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Using model from "/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/asr" Jul 10 15:44:28 snips snips-nlu[464]: INFO:snips_nlu_hermes : reload nlu engine event received Jul 10 15:44:28 snips snips-nlu[464]: INFO:snips_nlu_hermes : loading nlu engine "/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine" Jul 10 15:44:28 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Loading stemmer ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/resources/de/stemming/stems.txt") ... Jul 10 15:44:29 snips snips-asr[473]: INFO:snips_kaldi::decode::model: Loading model v2 Jul 10 15:44:29 snips snips-asr[473]: INFO:snips_kaldi::decode::model: Loading model v2 Jul 10 15:44:29 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Preparing decoder Jul 10 15:44:29 snips snips-asr[473]: INFO:snips_asr_hermes::handler : Preparing decoder Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Stemmer loaded Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Loading gazetteer 'top_200000_words_stemmed' ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/resources/de/gazetteers/top_200000_words_stemmed.txt") ... Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Gazetteer 'top_200000_words_stemmed' loaded Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Loading stop words ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/resources/de/stop_words.txt") ... Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::resources::loading : Stop words loaded Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::entity_parser::builtin_entity_parser : Loading builtin entity parser ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/builtin_entity_parser") ... Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::entity_parser::builtin_entity_parser : Builtin entity parser loaded Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::entity_parser::custom_entity_parser : Loading custom entity parser ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/custom_entity_parser") ... Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::entity_parser::custom_entity_parser : Custom entity parser loaded Jul 10 15:44:29 snips snips-nlu[464]: INFO:snips_nlu_lib::intent_parser::deterministic_intent_parser : Loading deterministic intent parser ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/deterministic_intent_parser") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::intent_parser::deterministic_intent_parser : Deterministic intent parser loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::intent_classifier::log_reg_intent_classifier: Loading log reg intent classifier ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/intent_classifier") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::intent_classifier::log_reg_intent_classifier: Log reg intent classifier loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_0") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_1") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_2") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_3") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_4") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_5") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_6") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_7") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_8") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_9") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_10") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_11") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_12") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_13") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_14") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_15") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_16") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_17") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_18") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : Loading CRF slot filler ("/var/lib/snips/injections/20190709T100353116590128/inj_20190710T134423123273301/nlu_engine/probabilistic_intent_parser/slot_filler_19") ... Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_lib::slot_filler::crf_slot_filler : CRF slot filler loaded Jul 10 15:44:30 snips snips-nlu[464]: INFO:snips_nlu_hermes : model loaded in 1522 ms

bvdh82 commented 5 years ago

Hi Alpha,

ich habe das gleiche oder zumindest ein ähnliches Problem. Auch bei mir kommt "The session was ended because one of the component didn't respond in a timely manner".

"sudo journalctl -f -u snips-skill-server" liefert leider auch keinen Hinweis.

In "sam service log" steht etwas von einem Sound dessen Id nicht gefunden wurde:

Aug 03 13:43:44 openhab snips-nlu[19308]: INFO:snips_nlu_hermes                                           : model loaded in 4709 ms
Aug 03 13:48:04 openhab snips-hotword[15320]: INFO:snips_hotword_hermes        : Hotword detected: site_id:default model:hey_snips (118ms ago)
Aug 03 13:48:04 openhab snips-dialogue[19306]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn off Hotword
Aug 03 13:48:04 openhab snips-dialogue[19306]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [d72ab65a-e0fc-4154-afc0-870c57cca664]: The session has been created because the site "default" is free
Aug 03 13:48:04 openhab snips-audio-server[19318]: INFO:snips_audio_portaudio      : Playing "2aa10ae0-dcd0-460b-97a3-78b0f0c500ea" using output "default", wav spec: WavSpec { channels: 2, sample_rate: 22050, bits_per_sample: 16, sample_format: Int }
Aug 03 13:48:04 openhab snips-hotword[15320]: INFO:snips_hotword_lib::audio    : Audio thread for site default stopped.
Aug 03 13:48:04 openhab snips-audio-server[19318]: INFO:snips_audio_portaudio      : Playing of "2aa10ae0-dcd0-460b-97a3-78b0f0c500ea" finished
Aug 03 13:48:04 openhab snips-tts[19310]: INFO:snips_tts_hermes: Received a play finished message from audio server with an unknown id '2aa10ae0-dcd0-460b-97a3-78b0f0c500ea'. Ignoring it.
Aug 03 13:48:05 openhab snips-asr[15319]: INFO:snips_asr_hermes::handler : Listening at site id default
Aug 03 13:48:05 openhab snips-asr[15319]: INFO:snips_asr_hermes::handler : Listening
Aug 03 13:48:05 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0       entered AsrRunner::run
Aug 03 13:48:05 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0+0.005 capture started
Aug 03 13:48:07 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0+2.865 endpoint detected (rule:2) frame:168 samples:43008 signal_time:2.688 rtf:0.794
Aug 03 13:48:07 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : Source thread stop on push: "Disconnected(..)"
Aug 03 13:48:07 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0+2.885 capture ended
Aug 03 13:48:07 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0+2.919 decoder finalized
Aug 03 13:48:08 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : T0+3.034 lookup and post-processing done
Aug 03 13:48:08 openhab snips-asr[15319]: INFO:snips_asr_lib::asr        : decoded: [Recognition { decoded_string: "schalte an", likelihood: 0.6455579, tokens: Some([Token { value: "schalte", confidence: 1.0, time: (0.0, 0.75), range: 0..7 }, Token { value: "an", confidence: 0.416745, time: (1.1066959, 2.496153), range: 8..10 }]) }]
 Aug 03 13:48:08 openhab snips-asr[15319]: INFO:snips_asr_hermes::handler : Publishing the recognition
Aug 03 13:48:08 openhab snips-asr[15319]: INFO:snips_asr_hermes::handler : Preparing decoder
Aug 03 13:48:08 openhab snips-audio-server[19318]: INFO:snips_audio_portaudio      : Playing "a16b48a5-cffa-4e7d-9a93-d52a38084c80" using output "default", wav spec: WavSpec { channels: 2, sample_rate: 22050, bits_per_sample: 16, sample_format: Int }
Aug 03 13:48:09 openhab snips-audio-server[19318]: INFO:snips_audio_portaudio      : Playing of "a16b48a5-cffa-4e7d-9a93-d52a38084c80" finished
Aug 03 13:48:09 openhab snips-tts[19310]: INFO:snips_tts_hermes: Received a play finished message from audio server with an unknown id 'a16b48a5-cffa-4e7d-9a93-d52a38084c80'. Ignoring it.
Aug 03 13:48:14 openhab snips-dialogue[19306]: INFO:snips_dialogue_lib::coordinator::coordinator: Session [d72ab65a-e0fc-4154-afc0-870c57cca664]: closing session which was active: Timeout
Aug 03 13:48:14 openhab snips-dialogue[19306]: INFO:snips_dialogue_lib::coordinator::coordinator: Site [default]: reset site -> turn off ASR and turn on Hotword
Aug 03 13:48:14 openhab snips-hotword[15320]: INFO:snips_hotword_lib::audio    : Audio thread for site default started.

Der HABot versteht meine Befehle :-/

Hast du noch eine Idee?

Danke und Gruß

Alpha200 commented 5 years ago

Hi, in #16 hatten wir festgestellt, dass es Probleme geben kann, wenn nicht alle Snips-Komponenten installiert sind. Kannst du das mal prüfen?

bvdh82 commented 5 years ago

Hi,

sam status gibt folgendes aus:

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

Service status:

snips-analytics .............. (not running)
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 (running)
snips-skill-server ........... 0.63.3 (running)
snips-tts .................... 0.63.3 (running)

Soweit ich das verstanden habe muss snips-analytics nicht laufen. Ich finde allerdings auch die Option nicht dieses zu aktivieren :-/

bvdh82 commented 4 years ago

Auch die dort genannten snips-Pakete sind alle installiert.

snips-analytics/unbekannt,now 0.63.3 armhf  [installiert]
snips-asr/unbekannt,now 0.63.3 armhf  [installiert]
snips-audio-server/unbekannt,now 0.63.3 armhf  [installiert]
snips-dialogue/unbekannt,now 0.63.3 armhf  [installiert]
snips-hotword/unbekannt,now 0.63.3 armhf  [installiert]
snips-injection/unbekannt,now 0.63.3 armhf  [installiert]
snips-kaldi-atlas/unbekannt,now 0.24.2 armhf  [installiert]
snips-nlu/unbekannt,now 0.63.3 armhf  [installiert]
snips-platform-common/unbekannt,now 0.63.3 armhf  [installiert]
snips-platform-voice/unbekannt,now 0.63.3 armhf  [installiert]
snips-skill-server/unbekannt,now 0.63.3 armhf  [installiert]
snips-template/unbekannt,now 0.63.3 armhf  [installiert]
snips-tts/unbekannt,now 0.63.3 armhf  [installiert]
snips-watch/unbekannt,now 0.63.3 armhf  [installiert]