xyz reagiert gerade nicht

Grml · 22 April 2019, 13:47:12

Hallo zusammen,

ich habe seit Januar den Alexa Skill in Betrieb der über den Vereinsserver läuft. Installation und Betrieb haben problemlos funktioniert. Bis gestern.
Seitdem habe ich nun das Problem, dass ich meine Geräte nicht mehr per Alexa schalten kann. Es kommt immer die Meldung "$xyz regiert gerade nicht".

Das Modul ist verbunden und läuft, meine Geräte sind auch unter alexa.amazon.de sichtbar. Über FHEM kann ich meine Geräte völlig problemlos schalten. Nur eben nicht über den Weg "Alexa".

Ein List sagt mir Folgendes:

Code Auswählen


FD         4
   FUUID      5c4adc66-f33f-b8cb-aa95-537111faa3067d18
   FVERSION   39_alexa.pm:0.190980/2019-04-02
   LAST_START 2019-04-22 10:06:05
   NAME       Alexa
   NOTIFYDEV  global,global:npmjs.*alexa-fhem.*
   NR         230
   NTFY_ORDER 50-Alexa
   PARTIAL    
   PID        10
   STARTS     1
   STATE      running /usr/bin/alexa-fhem
   TYPE       alexa
   active     0
   alexa-fhem version 0.5.14
   currentlogfile ./log/alexa-2019-04-22.log
   logfile    ./log/alexa-%Y-%m-%d.log
   CoProcess:
     cmdFn      alexa_getCMD
     name       alexaFHEM
     state      running /usr/bin/alexa-fhem
   Helper:
     DBLOG:
       alexaFHEM.ProxyConnection:
         LogDB:
           TIME       1555920372.25118
           VALUE      running; SSH connected
   READINGS:
     2019-04-22 10:06:05   alexaFHEM       running /usr/bin/alexa-fhem
     2019-04-22 10:06:12   alexaFHEM.ProxyConnection running; SSH connected
     2019-04-21 19:57:53   alexaFHEM.bearerToken crypt:[32-stelliger Key]
     2019-04-21 19:57:55   alexaFHEM.skillRegKey crypt:[84-stelliger Key]

Esjay · 22 April 2019, 13:57:56

Mach mal zuerst ein Update von Alexa-fhem.. Aktuell ist 0.5.26.. Dann im Log mal nachschauen und hier rein stellen..

Grüße

Grml · 22 April 2019, 14:11:29

Ok, Update habe ich gerade gemacht, hat aber nicht geholfen.

List:

Code Auswählen



 
Internals:
   FD         19
   FUUID      5c4adc66-f33f-b8cb-aa95-537111faa3067d18
   FVERSION   39_alexa.pm:0.190980/2019-04-02
   LAST_START 2019-04-22 14:05:34
   LAST_STOP  2019-04-22 14:03:05
   NAME       Alexa
   NOTIFYDEV  global,global:npmjs.*alexa-fhem.*
   NR         230
   NTFY_ORDER 50-Alexa
   PARTIAL    
   PID        3894
   STARTS     2
   STATE      running /usr/bin/alexa-fhem
   TYPE       alexa
   active     0
   alexa-fhem version 0.5.26
   currentlogfile ./log/alexa-2019-04-22.log
   logfile    ./log/alexa-%Y-%m-%d.log
   CoProcess:
     cmdFn      alexa_getCMD
     name       alexaFHEM
     state      running /usr/bin/alexa-fhem
   Helper:
     DBLOG:
       alexaFHEM:
         LogDB:
           TIME       1555934734.45931
           VALUE      running /usr/bin/alexa-fhem
       alexaFHEM.ProxyConnection:
         LogDB:
           TIME       1555934735.52525
           VALUE      running; SSH connected
   READINGS:
     2019-04-22 14:05:34   alexaFHEM       running /usr/bin/alexa-fhem
     2019-04-22 14:05:35   alexaFHEM.ProxyConnection running; SSH connected
     2019-04-21 19:57:53   alexaFHEM.bearerToken crypt:[32-stelliger Key]
     2019-04-21 19:57:55   alexaFHEM.skillRegKey crypt:[84-stelliger Key]

fhem.log:

Code Auswählen


2019.04.22 14:03:05 3: Alexa: read: end of file reached while sysread
2019.04.22 14:03:05 3: Alexa: stopped
2019.04.22 14:05:34 2: Alexa: starting alexa-fhem: /usr/bin/alexa-fhem -c ./alexa-fhem.cfg
2019.04.22 14:05:34 3: Alexa: starting
2019.04.22 14:05:34 3: Alexa: using logfile: ./log/alexa-2019-04-22.log

alexa-2019-04-22.log:

Code Auswählen


[4/22/2019, 2:03:03 PM] Got SIGTERM, shutting down alexa-fhem...
[4/22/2019, 2:03:03 PM] Reading alexaFHEM.ProxyConnection set to stopping;; alexa-fhem terminating
[4/22/2019, 2:03:03 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20Alexa%20alexaFHEM.ProxyConnection%20stopping%3B%3B%20alexa-fhem%20terminating%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:03:03 PM] Stopping SSH ...
[4/22/2019, 2:03:03 PM] Reading alexaFHEM.ProxyConnection set to stopped
[4/22/2019, 2:03:03 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20Alexa%20alexaFHEM.ProxyConnection%20stopped%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:03:03 PM] *** SSH: exited with 143
[4/22/2019, 2:05:34 PM] using config from ./alexa-fhem.cfg
*** CONFIG: parsed completely
[4/22/2019, 2:05:34 PM] os.homedir()=/root
[4/22/2019, 2:05:34 PM] this is alexa-fhem 0.5.26
[4/22/2019, 2:05:34 PM] connecting to FHEM ...
[4/22/2019, 2:05:34 PM] [FHEM] trying longpoll to listen for fhem events
[4/22/2019, 2:05:34 PM] [FHEM] starting longpoll: http://127.0.0.1:8083/fhem?XHR=1&inform=type=status;addglobal=1;filter=.*;since=null;fmt=JSON×tamp=1555934734678
[4/22/2019, 2:05:34 PM] [FHEM] got csrfToken: csrf_183860997132911
[4/22/2019, 2:05:34 PM] [FHEM] Checking devices and attributes...
[4/22/2019, 2:05:34 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7BAttrVal(%22global%22%2C%22userattr%22%2C%22%22)%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=jsonlist2%20TYPE%3Dalexa&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] [FHEM] waiting for events ...
[4/22/2019, 2:05:34 PM] [FHEM] Fetching FHEM devices...
[4/22/2019, 2:05:34 PM] [FHEM] fetching: http://127.0.0.1:8083/fhem?cmd=jsonlist2%20alexaName=..*&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] [FHEM] alexa device is Alexa
[4/22/2019, 2:05:34 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3B%7B%24defs%7BAlexa%7D-%3E%7B%22alexa-fhem%20version%22%7D%20%3D%20%220.5.26%22%7D%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bget%20Alexa%20proxyToken%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Blist%20Alexa%20.eventToken%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:34 PM] Server listening on: http://127.0.0.1:44649 for proxy connections
[4/22/2019, 2:05:34 PM] *** SSH: checking proxy configuration
[4/22/2019, 2:05:34 PM] sshautoconf: home=/root, spath=/root/.alexa, cpath=./alexa-fhem.cfg, sshpath=/root/.ssh
[4/22/2019, 2:05:34 PM] Passed config: {"sshproxy":{"description":"FHEM Connector","ssh":"/usr/bin/ssh","options":["-i","/root/.ssh/id_rsa","-p",58824,"fhem-va.fhem.de"],"bind-ip":"127.0.0.1","port":44649},"connections":[{"webname":"fhem","name":"FHEM","port":"8083","server":"127.0.0.1","uid":0,"filter":"alexaName=..*"}]}
[4/22/2019, 2:05:34 PM] sshautoconf: SSH key seems to exist
[4/22/2019, 2:05:35 PM] sshautoconf: Our SSH key is known at the reverse proxy, good!
[4/22/2019, 2:05:35 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bjsonlist2%20Alexa%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
*** FHEM: connected
[4/22/2019, 2:05:35 PM] [FHEM] got: 25 results



[**************MEINE GERÄTE MIT DEREN EIGENSCHAFTEN. GEKÜRZT DER ÜBERSICHTLICHKEIT HALBER**************]



[4/22/2019, 2:05:35 PM] BearerToken '...1831D' read from Alexa
[4/22/2019, 2:05:35 PM] [FHEM] got .eventToken
[4/22/2019, 2:05:35 PM] refreshing token
[4/22/2019, 2:05:35 PM] 39_alexa.pm is new version: true
[4/22/2019, 2:05:35 PM] sshautoconf: completed successfully
[4/22/2019, 2:05:35 PM] *** SSH: proxy configuration set up done
[4/22/2019, 2:05:35 PM] Reading alexaFHEM.ProxyConnection set to starting;; starting SSH
[4/22/2019, 2:05:35 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20Alexa%20alexaFHEM.ProxyConnection%20starting%3B%3B%20starting%20SSH%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:35 PM] Starting SSH with -R 1234:127.0.0.1:44649 -oServerAliveInterval=90 -i /root/.ssh/id_rsa -p 58824 fhem-va.fhem.de
[4/22/2019, 2:05:35 PM] got fresh token
[4/22/2019, 2:05:35 PM] Reading alexaFHEM.ProxyConnection set to running;; SSH connected
[4/22/2019, 2:05:35 PM] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20Alexa%20alexaFHEM.ProxyConnection%20running%3B%3B%20SSH%20connected%3B%7B%24defs%7BAlexa%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=csrf_183860997132911&XHR=1
[4/22/2019, 2:05:35 PM] *** SSH: proxy connection established
[4/22/2019, 2:05:35 PM] SSH: Welcome at the reverse proxy!  This pseudoshell does not react to any input - do not get irritated.

MadMax-FHEM · 22 April 2019, 14:46:02

Zitat[**************MEINE GERÄTE MIT DEREN EIGENSCHAFTEN. GEKÜRZT DER ÜBERSICHTLICHKEIT HALBER**************]

sowas ist kontraproduktiv...
...ohne GENAU zu lesen (Zeile für Zeile) hätte ich beim ersten "Quercheck" "festgestellt", dass ja keine Geräte gefunden werden -> ergo kann auch nichts per Sprache gesteuert werden...

Besser nix rumfummeln...

Gruß, Joachim

Grml · 22 April 2019, 14:49:00

Ok, sorry

Ich wollte Euch ersparen 25 Geräte mit ihren Eigenschaften im Log zu haben. Hab es oben nochmal etwas deutlicher abgesetzt.

Aber: Ich bin das Log nochmal akribisch durchgegangen, da sind wirklich nur Meldungn zu den Devices drin. Und gefunden werden eben 25 Stück. Lichter, Schalter und Thermostate.

MadMax-FHEM · 22 April 2019, 14:59:15

Zitat von: Grml am 22 April 2019, 14:49:00
Ok, sorry Ich wollte Euch ersparen 25 Geräte mit ihren Eigenschaften im Log zu haben. Hab es oben nochmal etwas deutlicher abgesetzt.

Aber: Ich bin das Log nochmal akribisch durchgegangen, da sind wirklich nur Meldungn zu den Devices drin. Und gefunden werden eben 25 Stück. Lichter, Schalter und Thermostate.

Gut.

Wollte auch nur anmerken, dass sowas oft zu "Verwirrungen" führen kann...

Ich habe gerade mal getestet: bei mir geht es noch (ältere Version von alexa-fhem [aber das ist wohl nicht entscheidend]) und auch alexa-fhem Connector...

Sollte also von Connector-Seite (und Amazon Seite) alles gut sein...

Wenn du etwas per fhem steuerst wird dann der Status in der Alexa-App auch entsprechend nachgeführt?

Was steht dann im alexa-fhem Log?

Wenn du mal zum Test einen Dummy anlegst und den suchen lässt bzw. an Alexa "weitergibst" funktioniert das?

Gruß, Joachim

Grml · 22 April 2019, 15:19:48

Zitat von: MadMax-FHEM am 22 April 2019, 14:59:15
Gut.

Wollte auch nur anmerken, dass sowas oft zu "Verwirrungen" führen kann...

Ich habe gerade mal getestet: bei mir geht es noch (ältere Version von alexa-fhem [aber das ist wohl nicht entscheidend]) und auch alexa-fhem Connector...

Sollte also von Connector-Seite (und Amazon Seite) alles gut sein...

Ok, danke schonmal dafür. Dann kann ich sicher sein, dass es nicht allgemein an Amazon/Alexa liegt.

Zitat
Wenn du etwas per fhem steuerst wird dann der Status in der Alexa-App auch entsprechend nachgeführt?

Was steht dann im alexa-fhem Log?

Nein, in der Alexa-App taucht die "Änderung" (bspw. die Lampe als eingeschaltet) nicht auf.
Dabei stelle ich gerade fest, dass ich mit der Alexa-App auch nichts mehr steuern kann.

Im Log steht nur

Code Auswählen


2019-04-22 15:09:30 caching: 3.Studio.HUELight.Schreibtisch-onoff: 1
2019-04-22 15:11:04 caching: 3.Studio.HUELight.Schreibtisch-bri: 254

Mehr kommt da nicht.

Zitat
Wenn du mal zum Test einen Dummy anlegst und den suchen lässt bzw. an Alexa "weitergibst" funktioniert das?

Gruß, Joachim

Nein, das geht auch nicht mehr.

MadMax-FHEM · 22 April 2019, 15:55:23

Hmm, dann funktioniert aber zumindest alexa-fhem und die Verbindung zu fhem...

...aber aus irgendwelchen Gründen wohl nicht das Weiterreichen zu Amazon...

Der Skill ist noch aktiviert!?
(nur zur Sicherheit mal nachsehen)

Ansonsten kann wohl nur Andre (justme1968) oder Georg (gvzdus) helfen...
...bzw. jemand der in der "neuen Art" der Verbindung "drin steckt"...
...kenne mich damit zu wenig aus (habe mich noch nicht genug damit beschäftigt), habe mich bislang auf die "alte Methode" konzentriert...
(die neue klappt ja meist ohne Probleme)

Falls sich keiner meldet evtl. mal hier: https://forum.fhem.de/index.php/topic,95272.0.html oder hier https://forum.fhem.de/index.php/topic,94817.msg875861.html posten...

Gruß, Joachim

Grml · 22 April 2019, 17:34:02

Hm, indirekt bin ich einen Schritt weiter... Dafür verstehe ich es nun noch weniger

Ich habe einen Snapshot der kompletten VM in der meine ganze FHEM-Umgebung in einem Docker-Container läuft von vor 3 Wochen wiederhergestellt. Direkt hat es wieder funktioniert.
Wenn ich nun lediglich die Docker-Container beende und wieder starte, habe ich das Problem wieder. Völlig ohne das ich auch nur einen Buchstaben an irgendeinem File ändere. Geschweige denn irgendwelche Konfigurationsänderungen machen würde oder gemacht hätte.

Es ist reproduzierbar:
Backup restoren => Funktioniert
Container stoppen und wieder starten => Funktioniert nicht mehr.

Ohne jegliche Änderung.

Ich verstehe es nicht...

MadMax-FHEM · 22 April 2019, 18:06:34

Hmmm, sorry aber bzgl. Docker kenne ich mich noch weniger aus als mit dem alexa-fhem Connector...

Aber ich glaube in einem der genannten Threads liest/schreibt jemand mit, der bzgl. Docker und alexa-fhem Ahnung hat (ein/das Docker-Image erstellt hat?)...

Vielleicht dort mal schauen oder schreiben...
...oder (zumindest) hier den "Titel" konkretisieren...

Viel Erfolg, Joachim

Grml · 22 April 2019, 18:52:36

Danke, schaue ich mir noch an.

Was ich gerade festgestellt habe: Durch das "docker-compose down" und anschließendes "docker-compose up -d" ändert sich der ProxyKey in FHEM. Sobald ich auf alexa.amazon.de den Skill deaktiviere, neu aktiviere/verknüpfe und dann nach dem ProxyKey gefragt werde, geht es. Bis zum nächsten "down/up". Mir war bis dahin nicht klar, dass sich der ProxyKey ändert.

Jetzt habe ich zumindest einen Workaround und kann im anderen Thread gezielt fragen.

MadMax-FHEM · 22 April 2019, 20:28:56

Dann is ja (fast) gut...

Viel Erfolg noch!

Gruß, Joachim