[gelöst] alexaFHEM: keine Geräte erreichbar, client not authorized

[gestein]

Hallo,

Vor einiger Zeit habe ich nach der Anleitung hier die Anbindung meiner FHEM-Geräte an Alexa geschafft.
https://wiki.fhem.de/wiki/FHEM_Connector_f%C3%BCr_Amazon_Alexa

Bis vor ein paar Tagen hat alles klanglos funktioniert.
Auf einmal reagiert kein Gerät mehr und im log erscheint ,,ERROR: InvalidAccessTokenError from 127.0.0.1".
Außer bei der Installation habe ich mich mit dem Connector und möglichen Fehlern nicht weiter beschäftigt.
Ich habe daher keine Ahnung, wo ich den Fehler suchen soll.

Kann mir bitte jemand einen Tipp geben, in welcher Richtung ich den Fehler suchen soll?
Welche Infos werden am besten noch benötigt?

Ist das Bearer-Token vielleicht falsch?

Danke für jede Hilfe.
Lg, Gerhard

Code Auswählen Erweitern

[26.12.2022, 15:06:11] 39_alexa.pm is new version: true
[26.12.2022, 15:06:11] sshautoconf: completed successfully
[26.12.2022, 15:06:11] *** SSH: proxy configuration set up done
[26.12.2022, 15:06:11] Reading alexaFHEM.ProxyConnection set to starting;; starting SSH
[26.12.2022, 15:06:11] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20alexa%20alexaFHEM.ProxyConnection%20starting%3B%3B%20starting%20SSH%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=fhemToken56_&XHR=1
[26.12.2022, 15:06:11] Starting SSH with -R 1234:127.0.0.1:33243 -oServerAliveInterval=90 -i /opt/fhem/.ssh/id_rsa -p 58824 fhem-va.fhem.de
[26.12.2022, 15:06:11] Reading alexaFHEM.ProxyConnection set to running;; SSH connected
[26.12.2022, 15:06:11] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20alexa%20alexaFHEM.ProxyConnection%20running%3B%3B%20SSH%20connected%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=fhemToken56_&XHR=1
[26.12.2022, 15:06:11] *** SSH: proxy connection established
[26.12.2022, 15:06:11] SSH: Welcome at the reverse proxy!  This pseudoshell does not react to any input - do not get irritated. 
  2022-12-26 15:06:23 caching: MQTT2_shellyswitch25_E098068D4E95-energy: 61819
[26.12.2022, 15:06:23] [FHEM]     caching: Custom Energy: 61819 (as number; from '61819')
  2022-12-26 15:07:13 caching: MQTT2_shellyswitch25_E098068D4E95-energy: 61853
[26.12.2022, 15:07:13] [FHEM]     caching: Custom Energy: 61853 (as number; from '61853')
  2022-12-26 15:08:05 caching: MQTT2_shellyswitch25_E098068D4E95-energy: 61887
[26.12.2022, 15:08:05] [FHEM]     caching: Custom Energy: 61887 (as number; from '61887')
[26.12.2022, 15:08:21] >>>> [ssh] {"directive":{"header":{"messageId":"0c4e3e65-7444-424f-8e87-b1833aba4d82","namespace":"Alexa.PowerController","name":"TurnOn","payloadVersion":"3","correlationToken":"-"},"endpoint":{"scope":{"type":"BearerToken","token":"4B615A00-DD234D0DDA7C73A8"},"endpointId":"5f7c3a15-f33f-0b7a-07b4-53bd5cc52b929960","cookie":{"device":"MQTT2_shellydimmer2_40F52001B3D1","fuuid":"5f7c3a15-f33f-0b7a-07b4-53bd5cc52b929960"}},"payload":{}}}
[26.12.2022, 15:08:21] client not authorized: {"error_index":"PbntrMC5d-uM6zGdUqzThAAAAAAAAAABb8gOwM8LSfDCPqEb9SXhpfrgTSzbcM0Z5StQE5oaA8W__M5rXdbO74tQxhOdh8KxWRIhz5bY4e6k5O57pMcHdYLzln_TTdG2OOs80hqkDwa4PQM7Wkd7NWqH4PKq4yZuxS0auLRcl4LS_xlJzoXpWcWPkfb4dnJQCGvGzDkGFOV3xMJtlqtmwcVLEqfMV_fED7ohOXpMqH8SXZVxFmu4StYii0-uBNB6bDuweQ==","error_description":"The request has an invalid parameter : access_token","error":"invalid_token"}
[26.12.2022, 15:08:21] ERROR: InvalidAccessTokenError from 127.0.0.1
[26.12.2022, 15:08:21] <<<< [ssh] {"header":{"namespace":"Alexa.ConnectedHome.Control","name":"InvalidAccessTokenError","payloadVersion":"2","messageId":"e2c93f79-b922-495e-b97f-0be07445e731"},"payload":{}}

[MadMax-FHEM]

Schon mal im Forum gesucht?

Schnelle Suche liefert z.B.: https://forum.fhem.de/index.php/topic,112070.msg1063634.html#msg1063634

Die dort bzw. im Wiki zu findenden Troubleshooting-Punkte hast du durch?

Was man leider nicht sieht (Log zu spät geschnitten) welche Version von alexa-fhem läuft.
Welches System, also HW und OS inkl. Version?

Gruß, Joachim

[gestein]

Hallo Joachim,

Schon mal im Forum gesucht?

Ja, natürlich. Aber leider nichts gefunden, das mir helfen würde das Problem einzugrenzen und anzugehen.

Schnelle Suche liefert z.B.: https://forum.fhem.de/index.php/topic,112070.msg1063634.html#msg1063634

Ja, den kenne ich.
Bei den Einstellungen im Skill steht in Grün ,,verknüpft".
Ich wollte nicht irgendwas deaktivieren und dann komplett im Wald stehen.

Die dort bzw. im Wiki zu findenden Troubleshooting-Punkte hast du durch?

Was man leider nicht sieht (Log zu spät geschnitten) welche Version von alexa-fhem läuft.
Welches System, also HW und OS inkl. Version?

Ja, eigentlich schon.
Ich habe einen Raspi 4 mit:
pi@fhem2:~ $ uname -a
Linux fhem2 5.10.11-v7l+ #1399 SMP Thu Jan 28 12:09:48 GMT 2021 armv7l GNU/Linux

Code Auswählen Erweitern

[28.12.2022, 15:39:00] using config from ./alexa-fhem.cfg
*** CONFIG: parsed completely
[28.12.2022, 15:39:01] os.homedir()=/opt/fhem
[28.12.2022, 15:39:01] this is alexa-fhem 0.5.64
[28.12.2022, 15:39:01] connecting to FHEM ...
[28.12.2022, 15:39:01] [FHEM] defaults to: will not send proactive events
[28.12.2022, 15:39:02] [FHEM] trying longpoll to listen for fhem events
[28.12.2022, 15:39:02] [FHEM] starting longpoll: http://127.0.0.1:8083/fhem?XHR=1&inform=type=status;addglobal=1;filter=.*;since=null;fmt=JSON×tamp=1672238342136
[28.12.2022, 15:39:10] [FHEM] got csrfToken: xXx
[28.12.2022, 15:39:10] [FHEM] Checking devices and attributes...
[28.12.2022, 15:39:10] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7BAttrVal(%22global%22%2C%22userattr%22%2C%22%22)%7D&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:10] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=jsonlist2%20TYPE%3Dalexa&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:10] [FHEM] waiting for events ...
[28.12.2022, 15:39:10] [FHEM] Fetching FHEM devices...
[28.12.2022, 15:39:10] [FHEM] fetching: http://127.0.0.1:8083/fhem?cmd=jsonlist2%20alexaName%3D..*&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:16] [FHEM] alexa device is alexa
[28.12.2022, 15:39:16] [FHEM] alexa will not send proactive events
[28.12.2022, 15:39:16] [FHEM] alexa uses ID: 6319b564-f33f-7e6e-5a0e-c2d47dac383e352b
[28.12.2022, 15:39:16] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22alexa-fhem%20version%22%7D%20%3D%20%220.5.64%22%7D%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:16] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bget%20alexa%20proxyToken%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:16] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Blist%20alexa%20.eventToken%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=xXx&XHR=1
[28.12.2022, 15:39:16] Server listening on: http://127.0.0.1:43967 for proxy connections
[28.12.2022, 15:39:16] *** SSH: checking proxy configuration
[28.12.2022, 15:39:16] sshautoconf: home=/opt/fhem, spath=/opt/fhem/.alexa, cpath=./alexa-fhem.cfg, sshpath=/opt/fhem/.ssh
[28.12.2022, 15:39:16] Passed config: { sshproxy:
   { description: 'FHEM Connector',
     ssh: '/usr/bin/ssh',
     options:
      [ '-i', '/opt/fhem/.ssh/id_rsa', '-p', 58824, 'fhem-va.fhem.de' ],
     'bind-ip': '127.0.0.1',
     server:
      Server {
        insecureHTTPParser: undefined,
        _events: [Object],
        _eventsCount: 3,
        _maxListeners: undefined,
        _connections: 0,
        _handle: [TCP],
        _usingWorkers: false,
        _workers: [],
        _unref: false,
        allowHalfOpen: true,
        pauseOnConnect: false,
        httpAllowHalfOpen: false,
        timeout: 0,
        keepAliveTimeout: 5000,
        maxHeadersCount: null,
        headersTimeout: 40000,
        _connectionKey: '4:127.0.0.1:0',
        [Symbol(IncomingMessage)]: [Function],
        [Symbol(ServerResponse)]: [Function],
        [Symbol(asyncId)]: 109 } },
  connections:
   [ { name: 'FHEM',
       uid: 999,
       filter: 'alexaName=..*',
       server: '127.0.0.1',
       webname: 'fhem',
       port: '8083' } ] }
[28.12.2022, 15:39:16] sshautoconf: SSH key seems to exist
[28.12.2022, 15:39:16] sshautoconf: Our SSH key is known at the reverse proxy, good!
[28.12.2022, 15:39:16] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bjsonlist2%20alexa%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=xXx&XHR=1
*** FHEM: connected
[28.12.2022, 15:39:18] [FHEM] got: 20 results
[28.12.2022, 15:39:18] [FHEM] MQTT2_shelly1pm_8CAAB50578BE is light
[28.12.2022, 15:39:18] [FHEM] MQTT2_shelly1pm_8CAAB50578BE has
[28.12.2022, 15:39:18] [FHEM]   Custom Power [power]
[28.12.2022, 15:39:18] [FHEM]   Custom Energy [energy]
[28.12.2022, 15:39:18] [FHEM]   On [state;on,off]
[28.12.2022, 15:39:18] [FHEM]   CurrentTemperature [temperature]
[28.12.2022, 15:39:18] [FHEM] MQTT2_shelly1pm_8CAAB50578BE will not send proactive events
[28.12.2022, 15:39:18] [FHEM] MQTT2_shelly1pm_8CAAB50578BE uses ID: 5eac42b0-f33f-0b7a-e07b-be903924a6aa0a6d
  2022-12-28 15:39:18 caching: MQTT2_shelly1pm_8CAAB50578BE-power: 0.00
[28.12.2022, 15:39:18] [FHEM]     caching: Custom Power: 0 (as number; from '0.00')
  2022-12-28 15:39:18 caching: MQTT2_shelly1pm_8CAAB50578BE-energy: 7796
[28.12.2022, 15:39:18] [FHEM]     caching: Custom Energy: 7796 (as number; from '7796')
  2022-12-28 15:39:18 caching: MQTT2_shelly1pm_8CAAB50578BE-state: off
  2022-12-28 15:39:18 caching: MQTT2_shelly1pm_8CAAB50578BE-temperature: 42.46

[28.12.2022, 15:39:18] [FHEM] dyAlleFenster is contact
[28.12.2022, 15:39:18] [FHEM] dyAlleFenster has
[28.12.2022, 15:39:18] [FHEM] dyAlleFenster will not send proactive events
[28.12.2022, 15:39:18] [FHEM] dyAlleFenster uses ID: 63300f72-f33f-7e6e-4ef6-200faf64c7fa821b
[28.12.2022, 15:39:23] BearerToken '...K4<8' read from alexa
[28.12.2022, 15:39:25] [FHEM] got .eventToken
[28.12.2022, 15:39:25] refreshing token
[28.12.2022, 15:39:25] got fresh token
[28.12.2022, 15:39:28] 39_alexa.pm is new version: true
[28.12.2022, 15:39:28] sshautoconf: completed successfully
[28.12.2022, 15:39:28] *** SSH: proxy configuration set up done
[28.12.2022, 15:39:28] Reading alexaFHEM.ProxyConnection set to starting;; starting SSH
[28.12.2022, 15:39:28] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20alexa%20alexaFHEM.ProxyConnection%20starting%3B%3B%20starting%20SSH%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=fhemToken56_&XHR=1
[28.12.2022, 15:39:28] Starting SSH with -R 1234:127.0.0.1:43967 -oServerAliveInterval=90 -i /opt/fhem/.ssh/id_rsa -p 58824 fhem-va.fhem.de
[28.12.2022, 15:39:28] Reading alexaFHEM.ProxyConnection set to running;; SSH connected
[28.12.2022, 15:39:28] [FHEM]   executing: http://127.0.0.1:8083/fhem?cmd=%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%201%3B%3Bundef%7D%3Bsetreading%20alexa%20alexaFHEM.ProxyConnection%20running%3B%3B%20SSH%20connected%3B%7B%24defs%7B%22alexa%22%7D-%3E%7B%22active%22%7D%20%3D%200%3B%3Bundef%7D&fwcsrf=fhemToken56_&XHR=1
[28.12.2022, 15:39:28] *** SSH: proxy connection established
[28.12.2022, 15:39:28] SSH: Welcome at the reverse proxy!  This pseudoshell does not react to any input - do not get irritated.

Vielen Dank für Deine Hilfe.
Gruß, Joachim

[MadMax-FHEM]

uname -a liefert zwar fein die Kernel Version aber das ist nicht (soo) interessant...

cat /etc/os-release wäre interessanter...

Aber alexa-fhem scheint ja aktuell zu sein.

In der zuletzt geposteten Ausgabe des alexa-fhem Logs sind keine Fehlermeldungen (mehr) drin.
Etwas gemacht?

Geht es?
Oder kommen/kämen die erst später im Log?

Naja, bzgl. des Fehlers gibt es ettliche Fünde im Forum.
Alle mit mehr oder weniger: deaktivieren/neu aktivieren oder gar inkl. deregistrieren beim Vereinsserver...

Bei mir läuft alexa-fhem schon von Beginn an (also inkl. noch "Vor-Connector-Zeiten") und hat einige System-Umzüge (HW-Upgrade und OS-Upgrade) überstanden ohne Probleme.
Daher kann ich nicht sagen was genau man bei was (für einem Fehler) tun muss...
Aber dazu sind ja die Erfahrungsberichte im Forum und das Wiki da...

Wenn du die Schritte im Wiki nicht probieren willst und auch die in den Threads nicht, was soll ich dann noch unterstützen?

Evtl. doch mal sehen, welche OS-Version, evtl. ist die ja zu alt (obwohl alexa-fhem ja irgendwie neu/aktuell ist)...

Gruß, Joachim

[juergen012]

Hallo,
bei meinem FHEM lief kürzlich auch Alexa nicht mehr. Nach einigen ergebnislosen Versuchen habe ich mich deregistriert und die App vom Handy gelöscht. Dann Alexa aus FHEM entfernt und nach der o.a. Anleitung neu installiert.
Jetzt funktioniert es wieder.
Gruß
Jürgen K.

[gestein]

In der zuletzt geposteten Ausgabe des alexa-fhem Logs sind keine Fehlermeldungen (mehr) drin.

Oder kommen/kämen die erst später im Log?

Die Fehlermeldungen kommen erst, wenn Alexa das Sprachkommando verstanden hat und den Befehl zurückschickt.

Naja, bzgl. des Fehlers gibt es ettliche Fünde im Forum.
Alle mit mehr oder weniger: deaktivieren/neu aktivieren oder gar inkl. deregistrieren beim Vereinsserver...

So habe ich es nun auch wieder gemacht.
Fehlersuchen hilft da wohl nicht viel (vor allem, wenn man sich nicht so auskennt).

Wenn du die Schritte im Wiki nicht probieren willst und auch die in den Threads nicht, was soll ich dann noch unterstützen?

So hatte ich das nicht gemeint.
Ich wollte nur nicht alleine in alle möglichen Richtungen suchen, wenn vielleicht jemand einen Anhaltspunkt gehabt hätte.
Die Schritte hatte ich ja alle durchgemacht.

Geholfen hat dann wirklich Deaktivieren, Löschen des Devices, etc.
So wie hier https://forum.fhem.de/index.php/topic,108461.msg1024377.html#msg1024377
Wahrscheinlich sehr brachial, aber es läuft wieder.

Danke, lg, Gerhard