Test Adapter Nuki-extended v2.0.x

Sandmanyz

Bei mir werden die Statuswerte des Nuki 2.0 Adapters (v1.0) irgendwann nicht mehr aktualisiert. Starte ich den Adapter neu, geht's erst einmal eine gewisse Zeit lang. Woran kann das liegen?

Zefau

@Sandmanyz Der Adapter stoppt die Aktualisierung, wenn es zu einem Fehler kommt. Gab es in den Fällen, wo das passiert ist, einen Fehler im Log ?

Sandmanyz

@Zefau

Diese Warnung steht im Log...

2019-08-09 20:01:50.132 - warn: nuki2.0 Connection settings for bridge incorrect with name XXXXXXX! No connection established. See debug log for more details.
2019-08-09 20:01:51.221 - warn: nuki2.0 Connection settings for bridge incorrect with name XXXXXXX! No connection established. See debug log for more details.
2019-08-09 20:01:52.309 - warn: nuki2.0 Connection settings for bridge incorrect with name XXXXXXX! No connection established. See debug log for more details.
2019-08-09 20:03:27.212 - warn: nuki2.0 Connection settings for bridge incorrect with name XXXXXXX! No connection established. See debug log for more details.

Ist ziemlich genau eine Stunde nach dem Neustart des Adapters.

Steht im "debug log" mehr? Wo kann ich das einsehen?

Zefau

@Sandmanyz Scheinbar sind die Zugangsdaten für deine Nuki Bridge falsch?

Sandmanyz

@Zefau
Die Zugangsdaten habe ich jetzt schon mehrfach kontrolliert, finde aber keinen Fehler.

Habe das Debug-Log aktiviert und den Adapter neugestartet. Die Werte werden fleißig aktualisiert und nach ca. 30 Minuten dieser Eintrag....

nuki2.0	2019-08-11 10:23:56.397	debug	getBridgeInfo(): 405 - "HTTP 405 Method not allowed"
nuki2.0	2019-08-11 10:23:56.395	warn	Connection settings for bridge incorrect with name Zuhause! No connection established. See debug log for more details.

Die Werte werden nach dem Fehler weiterhin aktualisiert. Habe die Tür, einige Minuten später, per App abgeschlossen und der Adapter stoppt sofort....

nuki2.0	2019-08-11 10:29:41.928	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:41.566	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:41.566	warn	Connection settings for bridge incorrect with name Zuhause! No connection established. See debug log for more details.
nuki2.0	2019-08-11 10:29:41.150	debug	updateLocks(): {"smartlockId":1234567,"accountId":abcdefgh,"type":0,"authId":987654321,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 10:29:26.925	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:26.565	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:26.564	warn	Connection settings for bridge incorrect with name Zuhause! No connection established. See debug log for more details.
nuki2.0	2019-08-11 10:29:26.128	debug	updateLocks(): {"smartlockId":1234567,"accountId":abcdefgh,"type":0,"authId":987654321,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 10:29:12.214	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:11.853	debug	getBridgeInfo(): 503 - "HTTP 503 Unavailable"
nuki2.0	2019-08-11 10:29:11.852	warn	Connection settings for bridge incorrect with name XXXXXX! No connection established. See debug log for more details.

Zefau

@Sandmanyz Ok, verstehe. Kannst du die Bridge API direkt aufrufen? Also per Browser die folgende URL:

http://<NukiBridgeURL>:8080/lockState?nukiId=<NukiID>&token=<NukiToken>

Bekommst du das Resultat angezeigt oder einen HTTP Fehler?

Sandmanyz

@Zefau
Das gibt der Browser aus....

{"mode": 2, "state": 1, "stateName": "locked", "batteryCritical": false, "success": true}

Zefau

@Sandmanyz Auf was hast du die refresh Zeit eingestellt?

Zefau

@Sandmanyz Versuch mal die aktuelle Version v1.0.1 auf Github: https://github.com/Zefau/ioBroker.nuki2

Sandmanyz

@Zefau sagte in [Neuer Adapter] Nuki:

@Sandmanyz Auf was hast du die refresh Zeit eingestellt?

15 Sekunden

Ich habe den Adapter aktualisiert. Um 13:45 Uhr habe ich den Adapter, nach der Einrichtung, gestartet. Alle Werte wurden aktualisiert. Dann habe ich über die App die Tür abgeschlossen. Seit dem Zeitpunkt werden die Werte wieder nicht aktualisiert.

"nuki2.0.door__XXXXXX.status.lastAction" hat den Wert "LOCK(2)" weil ich ja abgeschlossen habe aber...
"nuki2.0.door__XXXXXX.status.lockState" hat noch "UNLOCKED(3)" und...
"nuki2.0.door__XXXXXX.status.locked" ist "false".

Auszug aus dem Debug-Log....

nuki2.0	2019-08-11 13:47:19.474	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:47:04.485	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:46:49.470	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:46:34.466	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:46:19.459	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:46:04.456	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:45:49.452	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:45:34.445	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:45:19.444	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:45:06.044	info	Listening for Nuki events on port 51988.
nuki2.0	2019-08-11 13:45:05.139	debug	getBridgeInfo(): {"deviceType":0,"nukiId":123456789,"name":"XXXXXX","lastKnownState":{"mode":2,"state":3,"stateName":"unlocked","batteryCritical":false,"timestamp":"2019-08-11T11:43:40+00:00"},"nuki"
nuki2.0	2019-08-11 13:45:04.649	debug	State of nuki2.0.door__XXXXXX.action has changed {"val":0,"ack":true,"ts":1565443904532,"q":0,"from":"system.adapter.nuki2.0","lc":1565333305530}.
nuki2.0	2019-08-11 13:45:04.537	debug	updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3333333,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil
nuki2.0	2019-08-11 13:45:04.408	info	starting. Version 1.0.1 in /opt/iobroker/node_modules/iobroker.nuki2, node: v8.16.0
nuki2.0	2019-08-11 13:45:00.425	info	Adapter stopped und unloaded.

Seit dem nicht mehr aktualisiert wird, wird ins Log alle 15 Sekunden nur das geschrieben...

updateLocks(): {"smartlockId":123456789,"accountId":999999999,"type":0,"authId":3431648,"name":"XXXXXX","favorite":false,"config":{"name":"XXXXXX","latitude":xx.xxxxxx,"longitude":xx.xxxxxx,"capabil

Zefau

@Sandmanyz Bekomme es leider nicht reproduziert. Bei mir funktioniert es.

Hast du in deinen Einstellungen den Callback aktiviert? Also in den Adapter-Einstellungen der linke der beiden Haken in der Tabelle.
Dadurch erhältst du die Aktualisierungen vom Nuki. Dann sollte im Log etwas stehen wie Received payload via callback.

Sandmanyz

Erst einmal vielen Dank für deine Unterstützung!

@Zefau sagte in [Neuer Adapter] Nuki:

Received payload via callback

Davon finde ich im Log gar nichts, trotz des gesetzten Häkchens bei "Callback nutzen".

Ich bin von der Konfiguration über den Start des Adapters bis zum Test (öffnen/schließen) noch einmal alles durchgegangen (siehe PDF-Datei im Anhang). Das Problem besteht aber weiterhin.
Die Log-Datei im Anhang ist ebenfalls vom Start bis Test.

Screenshots.pdf
Log.txt

Vielleicht siehst du in dem Log oder meinen Screenshots einen Fehler?

Zefau

@Sandmanyz Wirklich sehr ausführlich dokumentiert, vielen Dank!

Kannst du mir per PN schicken, was du unter http://<bridgeIp>:8080/callback/list?token=deinToken bekommst?

EDIT: Sieht alles soweit gut aus im Log. Das wichtige Listening for Nuki events on port 51988 ist drin, aber der Event wird wohl nicht empfangen.

Zefau

@Sandmanyz kannst du die aktuelle v1.0.2 von Github installieren und mir das Log nochmal bereitstellen?

iobroker url https://github.com/Zefau/ioBroker.nuki2

Sandmanyz

@Zefau

Die Installation ist, soweit ich das als Leihe bewerten kann, sauber durchgelaufen. Ich konnte allerdings nirgends einen Hinweis auf die Version 1.0.2 finden. D.h., ich weiß jetzt nicht ob tatsächlich die 1.0.2 installiert ist.

In der Adapterübersicht steht beim Nuki Adapter "Installierte Version: 1.0.1". Das Verhalten ist derzeit noch unverändert.

nuki2.0	2019-08-12 21:23:43.505	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:23:28.506	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:23:13.493	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:22:58.492	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:22:43.472	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:22:28.478	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:22:13.458	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:21:58.466	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:21:43.450	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:21:28.461	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:21:13.451	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:20:58.459	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:20:43.426	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:20:29.917	info	Listening for Nuki events on port 51988.
nuki2.0	2019-08-12 21:20:29.917	debug	Adding callback with URL http://192.168.xxx.x:51988/nuki-api-bridge to Nuki Bridge with name XXXXXXX.
nuki2.0	2019-08-12 21:20:29.915	debug	Retrieved current callbacks from Nuki Bridge with name XXXXXXX: [{"domain":null,"_events":{},"_eventsCount":0,"_maxListeners":100,"connection":{"ip":"192.168.102.20","port":8080,"token":"8HIh0A","dela
nuki2.0	2019-08-12 21:20:29.004	debug	getBridgeInfo(): {"deviceType":0,"nukiId":222222222,"name":"XXXXXXX","lastKnownState":{"mode":2,"state":1,"stateName":"locked","batteryCritical":false,"timestamp":"2019-08-12T19:12:24+00:00"},"nuki":{
nuki2.0	2019-08-12 21:20:28.660	debug	State of nuki2.0.door__XXXXXXX.action has changed {"val":0,"ack":true,"ts":1115622628593,"q":0,"from":"system.adapter.nuki2.0","lc":1115622495570}.
nuki2.0	2019-08-12 21:20:28.554	debug	updateLocks(): {"smartlockId":222222222,"accountId":999999999,"type":0,"authId":1234567,"name":"XXXXXXX","favorite":false,"config":{"name":"XXXXXXX","latitude":11.111111,"longitude":11.111111,"capabil
nuki2.0	2019-08-12 21:20:28.399	info	starting. Version 1.0.1 in /opt/iobroker/node_modules/iobroker.nuki2, node: v8.16.0

Zefau

@Sandmanyz habe vergessen die Version anzupassen, insofern passt v1.0.1. Das Log erhält auch die zusätzlichen Einträge, die ich hinzugefügt habe, aber merkwürdigerweise nicht alle.

Kommst du per SSH oder Samba auf dein ioBroker und könntest mal etwas nachprüfen?
Und zwar die Datei /opt/iobroker/node_modules/iobroker.nuki2/node_modules/nuki-bridge-api/package.json (oder wenn diese nicht existiert, dann /opt/iobroker/node_modules/nuki-bridge-api/package.json) hat einen Eintrag namens "version". Was steht da bei dir?

Sandmanyz

@Zefau sagte in [Neuer Adapter] Nuki:

/opt/iobroker/node_modules/iobroker.nuki2/node_modules/nuki-bridge-api/

Der Pfad /opt/iobroker/node_modules/iobroker.nuki2/node_modules/nuki-bridge-api bzw. der Ordner nuki-bridge-api existiert nicht. Der Pfad /opt/iobroker/node_modules/nuki-bridge-api/ hingegen ist vorhanden und es liegt auch die Datei package.json drin.

Ich habe mal einen Screenshot gemacht....

Zefau

@Sandmanyz Ok. Mist, ist die richtige Version Langsam weiß ich leider nicht weiter.

Der Log-Eintrag aus Zeile #L316 ist bei dir vorhanden (https://github.com/Zefau/ioBroker.nuki2/blob/master/nuki2.js#L316).
Aber weder der Log-Eintrag in #L322 noch in #L329 bzw. #L333 wird bei dir angezeigt, obwohl mindestens einer von diesen im Log stehen müsste.

Zefau

@Sandmanyz Wir können es allerdings "manuell" beheben, allerdings weiß ich nicht, ob das von Dauer ist.

Wenn du http://<bridge_ip>:<bridge_port>/callback/list?token=<bridgeToken> aufrufst
alle Einträge dort löschen mittels http://<bridge_ip>:<bridge_port>/callback/remove?id=<callback_id>&token=<bridgeToken>
den korrekten Eintrag manuell anlegen mittels http://<bridge_ip>:<bridge_port>/callback/add?url=http%3A%2F%2F<ioBrokerIp>%3A51988%2Fnuki-api-bridge&token=<bridgeToken>

EDIT: Kannst mir dann gerne nochmal die Ausgabe aus #1 vorher und nachher schicken, dann prüfe ich das nochmal quer.

Sandmanyz

@Zefau sagte in [Neuer Adapter] Nuki:

EDIT: Kannst mir dann gerne nochmal die Ausgabe aus #1 vorher und nachher schicken, dann prüfe ich das nochmal quer.

Verflucht, nicht gelesen. Habe also keinen vorher Screenshot. Ich weiß nur, dass der Broker mit zwei Ports (8080 und 51988) eingetragen war und die IDs 0, 1 und 2 belegt waren. D.h., ich weiß nicht mehr was bei der dritten ID hinterlegt war.

Jetzt gibt es genau einen Eintrag: {"callbacks": [{"id": 0, "url": "http://192.168.xxx.x:51988/nuki-api-bridge"}]} (192.168.xxx.x = mein Broker).

Wenn ich den Link http://192.168.xxx.x:51988/nuki-api-bridge aufrufe erscheint im Browser Cannot GET /nuki-api-bridge. Soll das so?

Es scheint erst einmal zu funktionieren. Die Werte werden nun aktualisiert. Wie zuverlässig das ist, wird sich zeigen.

Vielen Dank für deine Unterstützung

NEWS

Test Adapter Nuki-extended v2.0.x

Support us

1.2k
Online

30.5k
Users

76.7k
Topics

1.2m
Posts

NEWS

Test Adapter Nuki-extended v2.0.x

Support us

1.2kOnline

30.5kUsers

76.7kTopics

1.2mPosts

1.2k
Online

30.5k
Users

76.7k
Topics

1.2m
Posts