[gelöst] ich krieg echodevice nicht an's Laufen

[wolliballa73]

Hallo zusammen,

ich würde gerne das Modul echodevice einsetzen, komme aber irgendwo bei der Anmeldung nicht weiter und hoffe auf Hilfe.

Die Installation hat mit der Anleitung von https://www.mwinklerblog.de/smarthome/eigene-module/echodevice/#Beschreibung funktioniert. Ich habe die NPM-Login-Methode verwendet (https://www.mwinklerblog.de/modul-echodevice-npm/), die soweit auch ganz gut funktioniert hat; lediglich der Link zur Amazon-Anmeldung wurde mir nicht automatisch angezeigt, sondern musste diesen separat im Browser öffnen (http://192.168.1.11:3002), dort konnte ich die Anmeldung mir 2FA erfolgreich durchführen (Meldung: "Amazon Alexa Cookie successfully retreived")
Bis hierhin sieht aus meiner Sicht also alles halbwegs gut aus.

Jetzt scheint es aber irgendwo zu haken - mein Device (Echo) steht auf dem STATE "INITIALIZED", bei z.B. "get Echo devices" erhalte ich "Echo is not connected. Aborting..."
Was läuft da falsch?

get Echo status:

Code Auswählen Erweitern

Modul Infos:

Beschreibung    Bereich    Wert
STATE    Reading unbekannt
Version    Reading 0.2.14
NPM Cookie Version    Reading 4.1.3
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
COOKIE_MODE    Reading NPM
amazon_refreshtoken    Reading wait for refreshtoken
room    Attribut Amazon
icon    Attribut echo

Amazon Cookie:

Beschreibung    Bereich    Wert
.COOKIE    Reading unbekannt....
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
amazon_refreshtoken    Reading wait for refreshtoken
.COOKIE    Helper ....
.COMMSID    Helper ....
.CSRF    Helper ....
.DIRECTID    Helper ....
RUNLOGIN    Helper 0
RUNNING_REQUEST    Helper
LOGINERROR    Helper 0

Code Auswählen Erweitern

defmod Echo echodevice xxx@xxx.xx xxx
attr Echo icon echo
attr Echo room Amazon

setstate Echo INITIALIZED
setstate Echo 2022-12-26 11:17:14 COOKIE_MODE NPM
setstate Echo 2022-12-26 11:17:33 COOKIE_TYPE NEW
setstate Echo 2022-12-26 11:16:44 amazon_refreshtoken wait for refreshtoken
setstate Echo 2022-12-26 11:17:33 autocreate_devices stop
setstate Echo 2022-12-26 11:17:33 version 0.2.14


Ich bin für jede Hilfe dankbar

[wolliballa73]

Noch ein Nachtrag:
Im Log bekomme ich im Sekundentakt

Code Auswählen Erweitern

2022.12.27 09:20:51 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login Refresh Mon Dec 26 20:02:58 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST false

Vielen Dank für sachdienliche Hinweise

VG,
Matze

[wolliballa73]

... und hier ein noch (hoffentlich) aussagekräftigerer Auszug aus dem Logfile vom Zeitpunkt des "set Echo NPM_Install new"
Ich habe das Device vorher nochmal gelöscht, das System neu gestartet und das Device neu angelegt. Weiterhin bekomme ich den Link für die Amazon-Anmeldung nicht angezeigt, sondern muss diesen manuell ausführen, was dann auch augenscheinlich funktioniert.

Code Auswählen Erweitern

2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Node Version 10.24.0
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Check Interface=lo IP=127.0.0.1
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew]   Ignor Interface=lo IP=127.0.0.1
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Check Interface=lo IP=::1
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew]   Ignor Interface=lo IP=::1
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Check Interface=eth0 IP=192.168.1.11
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew]   Result Interface=eth0 IP=192.168.1.11
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Check Interface=eth0 IP=fe80::721:e2ea:53d2:d70d
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Proxy Port 3002 is free
2022.12.27 09:51:12 3: [Echo] [echodevice_NPMLoginNew] Proxy IP 192.168.1.11
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Login-Amazon-URL: amazon.de
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Base-Amazon-URL: amazon.com
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Device-App-Name: ioBroker Alexa2
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Accept-Language: de-DE
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Mode enabled if needed: 192.168.1.11:3002 to listen on 192.168.1.11
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 1: get first cookie and authentication redirect
2022.12.27 09:51:16 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"alexa.amazon.de","path":"","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*"}}
2022.12.27 09:51:17 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (302) - Redirect to https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&
2022.12.27 09:51:17 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*","Cookie":""},"body":""}
2022.12.27 09:51:18 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)
2022.12.27 09:51:18 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id = 261-5938668-2526121
2022.12.27 09:51:18 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id-time = 2302851078l
2022.12.27 09:51:49 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 2: login empty to generate session
2022.12.27 09:51:49 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&","Cookie":"session-id=261-5938668-2526121; session-id-time=2302851078l","Accept":"*/*"},"gzip":true,"body":"appActionToken=zaWC77KaCfVfKJRz2TY6j2Fj2B83ZUsj3D&appAction=SIGNIN&openid.return_to=ape%3AaHR0cHM6Ly9hbGV4YS5hbWF6b24uZGUv&prevRID=ape%3AVDhLN1pOMVZUUUtQVllCRjZWTjQ%3D&workflowState=eyJ6aXAiOiJERUYiLCJlbmMiOiJBMjU2R0NNIiwiYWxnIjoiQTI1NktXIn0.SFAdw14UiGkFg3XPUWNz43S9dxusfTPvNONpgZoVcRtxjQUASlnd3A.VQ6XcPpHITf8RfnR.r4Z1Q18JcbkbgruWgdRMaLd-mq6Qmhdxu5_OmX0mAnT5s8TFj1EEWGEjmhvqTzqUNQ_3cOySON4Mv3INco67laUraSb2IabVg1DmtI2EMAKLqoMFagL0LkRXJR2b80qdCW9dJgZ7mmMWp3QFhzTMwRqMiVsB1E3WTYRblHQNNRoIgNSp2DnLuqSZ-FEjzen6G_VLaCwzmaV6jNIVW490wgbGAdF8F3EizL8bJzlFDfRYmX7s8d9sqyasqdwmKvkxWJLnisbLMWavfJ8-RXxLyKO4MPG9lTSRQfDNP5iBKcmfzvDiioIC7kqUHOVegvlwoOpMx-Jh7mNDdqNAxd0y.XnnflfNTcyFq9VO05KDEzw&giveFocusOnMobileToFirstControlFailingValidation=true&showPasswordChecked=false"}
2022.12.27 09:51:50 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)
2022.12.27 09:51:50 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Update Cookie session-id-time = 2302851110l
2022.12.27 09:51:50 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie ubid-acbde = 257-4722065-1348508
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 3: login with filled form, referer contains session id
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"https://www.amazon.de/ap/signin/261-5938668-2526121","Cookie":"session-id=261-5938668-2526121; session-id-time=2302851110l; ubid-acbde=257-4722065-1348508","Accept":"*/*"},"gzip":true,"body":"appActionToken=zaWC77KaCfVfKJRz2TY6j2Fj2B83ZUsj3D&appAction=SIGNIN&openid.return_to=ape%3AaHR0cHM6Ly9hbGV4YS5hbWF6b24uZGUv&prevRID=ape%3ANTM1OURXUTYxNjFRSE1OSkQ3UjE%3D&workflowState=eyJ6aXAiOiJERUYiLCJlbmMiOiJBMjU2R0NNIiwiYWxnIjoiQTI1NktXIn0.nrmeOSgqCZtdoVrwlbDVsytJdeSZO6w7zSxBdFkIG7NvxTR7TGcDcg.6HJ2CD3ue2Bw5N5Z.OycjJ17CBdxR8hcfJZqcyUFkeAW7sgxl5LPUmgC76Y2wZDbXNq4U5hBZYmtoRQ2DlsjB2nDVT8WDirkb3Hq4uT9Lw0yTUblvP_JqK2h9pEXHXAn9B8neQKWI2qzRyjwiIk7LwlQyNJ-wcoeDNX_ZaNy8ECsucmmRxPbq_jKq5n7pv1Xy1cYLV0vRh0tcPtjgSTDtlygVihd4gcUxzeZoybZOKYALEeNB4GmJPKthRgkaRrGwmW-YMp4fsu8qGx3rvfnsHRrdcSh42g0F_Q2RqEJVGtxwbIB8s45F9Gx3Rp9bUX8lP1loDxfLrj4nBjiC.14w9G7EGOhMOgV-71Y_3iw&giveFocusOnMobileToFirstControlFailingValidation=true&showPasswordChecked=false&email=LoginFHEM&password=xxxx"}
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Proxy Init: loaded temp data store ass fallback former data
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Proxy Init: reuse frc from former data
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Proxy Init: reuse map-md from former data
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Proxy Init: reuse deviceId from former data
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result [HPM] Proxy created: !/cookie-success  -> https://alexa.amazon.com
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/www.amazon.com" ~> ""
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/alexa.amazon.com" ~> ""
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Server listening on port 3002
2022.12.27 09:52:21 3: [Echo] [echodevice_NPMLoginNew] Result: Bitte den Link anklicken und die Amazonanmeldung durchfuehren.
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:51:11 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Node Version 10.24.0
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Check Interface=lo IP=127.0.0.1
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew]   Ignor Interface=lo IP=127.0.0.1
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Check Interface=lo IP=::1
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew]   Ignor Interface=lo IP=::1
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Check Interface=eth0 IP=192.168.1.11
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew]   Result Interface=eth0 IP=192.168.1.11
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Check Interface=eth0 IP=fe80::721:e2ea:53d2:d70d
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 192.168.1.11:3002       0.0.0.0:*               LISTEN     
2022.12.27 09:52:33 3: [Echo] [echodevice_NPMLoginNew] Proxy Port 3002 is in use
2022.12.27 09:52:34 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
2022.12.27 09:52:35 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
2022.12.27 09:52:36 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
[...]
2022.12.27 09:53:28 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
2022.12.27 09:53:41 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken=null EXIST 392create-cookie.js = true
        2022.12.27 09:53:50 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken="frc=KbQTUioBONzyxARDDSn8L0XT98Mpt38fpZQKUPDiwWXrPwOoQw+ypKUgXKsi[...] ==" EXIST false
2022.12.27 09:53:51 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken="frc=KbQTUioBONzyxARDDSn8L0XT98Mpt38fpZQKUPDiwWXrPwOoQw+ypKUgXKsi[...] ==" EXIST false
2022.12.27 09:53:52 3: [Echo] [echodevice_NPMWaitForCookie] [NPM Login New Tue Dec 27 09:52:33 2022] wait for refreshtoken / refreshtoken unkown!! refreshtoken="frc=KbQTUioBONzyxARDDSn8L0XT98Mpt38fpZQKUPDiwWXrPwOoQw+ypKUgXKsi[...] ==" EXIST false

Ein "set Echo NPM_Login refresh" führt zu

Code Auswählen Erweitern

Das angegebene Refreshtoken Cookie ist ungeueltig! Refreshtoken="0"

[wolliballa73]

Ich antworte mir mal selber und möchte mich bei HansDampfHH bedanken, der in diesem Beitrag die Lösung hatte:
https://forum.fhem.de/index.php/topic,82631.msg1222387.html#msg1222387

Wenn ich das Device im Inkognito-Tab anlege, klappt das (bisher)