Failed to get home details: TypeError: Cannot read properties of null (reading 'rrHomeId')

[Standarduser]

Der Saugroboter (S5 Max) lässt sich nicht per ioBroker steuern, die Karte sowie alle weiteren Daten werden nicht aktualisiert

Das Debug-Log gibt folgendes aus:

roborock.0
2023-12-07 16:17:58.333	error	Failed to get home details: TypeError: Cannot read properties of null (reading 'rrHomeId')

roborock.0
2023-12-07 16:17:58.069	info	starting. Version 0.4.4 in /opt/iobroker/node_modules/iobroker.roborock, node: v18.17.1, js-controller: 5.0.12

roborock.0
2023-12-07 16:17:57.256	debug	Plugin sentry Initialize Plugin (enabled=true)

roborock.0
2023-12-07 16:17:57.045	debug	States connected to redis: 127.0.0.1:9000

roborock.0
2023-12-07 16:17:56.942	debug	States create User PubSub Client

roborock.0
2023-12-07 16:17:56.942	debug	States create System PubSub Client

roborock.0
2023-12-07 16:17:56.916	debug	Redis States: Use Redis connection: 127.0.0.1:9000

roborock.0
2023-12-07 16:17:56.867	debug	Objects connected to redis: 127.0.0.1:9001

roborock.0
2023-12-07 16:17:56.862	debug	Objects client initialize lua scripts

roborock.0
2023-12-07 16:17:56.766	debug	Objects create User PubSub Client

roborock.0
2023-12-07 16:17:56.766	debug	Objects create System PubSub Client

roborock.0
2023-12-07 16:17:56.765	debug	Objects client ready ... initialize now

roborock.0
2023-12-07 16:17:56.708	debug	Redis Objects: Use Redis connection: 127.0.0.1:9001

roborock.0
2023-12-07 16:17:53.319	info	terminating

roborock.0
2023-12-07 16:17:52.818	info	Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason

roborock.0
2023-12-07 16:17:52.818	debug	Plugin sentry destroyed

roborock.0
2023-12-07 16:17:52.817	info	terminating

roborock.0
2023-12-07 16:17:52.816	info	Got terminate signal TERMINATE_YOURSELF

Ein Neustart der Instanz bringt keine Abhilfe.

[copystring]

Ging das bei dir irgendwann mal?

[Standarduser]

Ja natürlich, sogar sehr zuverlässig. Zuletzt wurden Daten am 24.11. übermittelt. Möglicherweise habe ich da den Adapter aktualisiert, ich kann mich aber nicht mehr genau erinnern.

[copystring]

Und neu installieren vom Adapter hat nicht geholfen?

[Standarduser]

Hab ich mit Absicht noch nicht gemacht, für den Fall, dass du eine Idee hast, was ich ausprobieren kann/soll

[copystring]

Ganz ehrlich? Keine Ahnung. Er holt sich bei dir homedata nicht. Das geht eigentlich nur dann, wenn beim anmelden ein Fehler passiert. Diese Fehler werden aber bereits abgefangen.

[Standarduser]

Macht es Sinn, vielleicht etwas mehr zu loggen, damit man den Fehler identifizieren kann?

[copystring]

Glaube nicht. Der Fehler liegt nicht am Adapter, sondern in deinem Setup. Aus irgendeinen Grund werden die Daten nicht richtig abgerufen.
Siehe diese Zeile: https://github.com/copystring/ioBroker.roborock/blob/dev/main.js#L177
Zwei Zeilen darüber wird versucht, sich anzumelden. Bei dir klappt das eben nicht. Installiere bitte erstmal neu, bevor wir hier ein Fass aufmachen.

[Standarduser]

Das Löschen aller Datenpunkte und anschließender Neustart des Adapters haben das Problem behoben. Der Saugroboter lässt sich nun wieder steuern.

Ich bin mir nicht ganz sicher, aber mir scheint das Problem eher beim Adapter zu liegen, sonst hätte diese Lösung nicht funktionieren können.

[copystring]

Ja, wenn du alle Datenpunkte löschst, wird dadurch ein neuer Token generiert. Damit klappt dann auch wieder die Anmeldung.
Ich könnte etwas einbauen, dass den Token bei diesem Fehler löscht und neu erstellt. Es wird aber schwer, deinen Fehler erneut zu reproduzieren.

[Standarduser]

Für mich klingt das wie ein Plan. Eventuell wäre es ja auch sinnvoll, dass der Token entweder beim Neustart des Adapters generiert wird, oder aber, wenn man Benutzername und Passwort neu eingibt. Letzteres hatte ich nämlich zuvor auch probiert, was jedoch nicht zum Erfolg geführt hat.

Vielen Dank für deine Hilfe und den tollen Adapter.

[stale]

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

[copystring]

Unstale

[copystring]

Bitte die neue dev testen und eine Rückmeldung geben. Danke.

https://github.com/copystring/ioBroker.roborock/tree/dev

[jahnbes]

Tut mir leid, die Rückmeldung ist leider: Fehler weiterhin da. userData wird nun hin und wieder gelöscht und neu erstellt, das behebt aber den Fehler nicht.
Eigenartig ist, dass sich der Robo, wenn man ihm genug Zeit gibt, z.B. über Nacht, von selbst heilt. Dann geht wieder alles. Aber leider nur kurz, vielleicht eine Stunde oder so, dann ist der Fehler wieder da. Am Tag habe ich diese Selbstheilungskräfte noch nicht beobachtet.
Ein Neustart des Adapters hilft bei mir nicht.!
Die Bedienung über die App geht immer.
Ich werde versuchen, ein log und debug vom Fehler zu erstellen.
Gruß Bert.

[copystring]

Hallo @jahnbes,

hast du die Möglichkeit gefunden, den Fehler inklusive Protokoll nochmal zu reproduzieren?

Viele Grüße

[jahnbes]

Hallo @copystring,
ich versuche es gleich noch einmal, momentan streikt der Robbi.
Viele Grüße, Bert.

[jahnbes]

log_roborock_20240105.txt
Hallo @copystring ,
nun habe ich es geschafft, hier das angesprochene debug-file.
Der Ablauf war wie folgt:

1. Heute vormittag lief Robo ohne Problem.
2. Gegen 17:00 Uhr Fehlerzustand bemerkt
3. 17:03 Uhr Debug-Modus eingeschaltet, homeData und userData waren vorhanden und ausgefüllt
4. 17:05 Uhr homeData und userDate gelöscht
5. Sofort anschließend die Instanz neu gestartet
6. Sofort danach beides wieder da
7. Robo läuft ohne Problem

Leider tritt dieser Fehler täglich auf mit der Heilung über Nacht und den Problemen so am Mittag. Wobei der Robo bei mir üblicherweise früh zweimal 10 Minuten läuft, mehr nur am Donnerstag.

Viele Grüße, Bert.
Anhang:
Diesmal war der Robo nach ca. einer Stunde wieder im gewohnten Fehlerzustand. Mal sehen, wie er morgen früh so drauf ist.

[copystring]

Hallo @jahnbes,

das ist mal ein ziemlich gutes Log. Roborock macht deine Zugangsdaten zum MQTT-Server ungültig. Diese werden beim Löschen von UserData neu erstellt. Das wäre allerdings problematisch. Ich habe das jetzt nochmal mit der App verglichen. Dort passiert das nie. Es liegt wohl am Adapter. Ich habe in der neuen dev die Verbindung zum MQTT-Server von Roborock leicht angepasst. Wäre klasse, wenn das schon ausreichen würde. Magst du das mal testen?

[jahnbes]

Hallo @copystring,
das hört sich erst mal gut an. Ich gehe davon aus, ich soll die dev-Version testen? Mache ich, allerdings erst in der kommenden Woche, vorher habe ich keine Gelegenheit.
Viele Grüße, Bert.

[copystring]

Genau. In der neuen dev sind die Änderungen vorhanden.

[jahnbes]

Hallo @copystring,
es sieht gut aus! Ich habe gestern kurz nach 17:00 Uhr die dev-Version installiert und seitdem läuft der Robo ohne Probleme, der obige Fehler ist bisher (8. Januar 2024 08:42:13) nicht mehr aufgetreten.
(Zur Orientierung, nach der dev-Installation wurde in der Instanz der Versionname 0.5.1 angezeigt.)
Ich werde weiter beobachten.
Gibt es Deinerseits Bedarf nach einer Kommunikation bezüglich des Wiki?
Danke und Gruß, Bert.

[jahnbes]

LEIDER
8. Januar 2024 12:13:43
Der Fehler ist in alter Schönheit mit den alten Begleitumständen wieder da, HomeDate ist wieder null, UserDate ist ausgefüllt.
Gruß Bert..

[jahnbes]

Hallo @copystring,
soll ich noch mal ein log-file im debug-Zustand erstellen und wenn ja, mit welcher Version des Adapter. Ich habe heute mal die 0.3.1 als älteste verfügbare ausprobiert, das gleiche Problem wie bei 0.4.4 und bei dev, leider.
Viele Grüße, Bert.

[copystring]

Hallo @jahnbes, sehr gerne. Nur weiß ich leider nicht, wann ich mich darum kümmern kann. Wenn ich für irgendwas Zeit finden kann, dass ist dieses Problem mit höchster Priorität.

[jahnbes]

Hallo @copystring,
mach Dir keinen Stress, Tempo ist nicht notwendig. Frage, bei welcher Adapter-Version soll ich das log-file erstellen, 0.4.4 oder dev?
Ich hoffe, im Laufe der Woche dann dazu zu kommen.

[copystring]

Am besten mit der dev

[copystring]

Ich habe in dev eine neue Anpassung vorgenommen. Bisher wurde UserData gelöscht, wenn es ein Timeout bei der Verbindung gab. Das ist jedoch fatal, da dies ein neuen Loginvorgang voraussetzt. Das sollte aber nie benötigt werden. Die App tut dies ebenfalls nie. Nur dann, wenn das Konto abgemeldet wurde.
Vielleicht ist das Problem dadurch behoben. Wäre klasse, wenn du das mal testen würdest.

[copystring]

OK. Top.
Nun zurück zum ursprünglichen Problem.

2023-12-07 16:17:58.333 error Failed to get home details: TypeError: Cannot read properties of null (reading 'rrHomeId')

Taucht das noch auf bzw. ist das doch aktuell?

[jahnbes]

Hallo copystring!
Leider offensichtlich ja:
Bis 23:25 Uhr lief gestern alles ohne Probleme, dann alter Fehler. Habe ich erst heute früh gemerkt, iobroker hatte den Robi-Adapter wegen häufiger Probleme abgeschaltet.
Hier das log-file von gestern. muss jetzt aber weg, kann erst morgen Abend wieder drauf schauen:
roborock_log_20240120_1.txt
Gruß Bert.

[copystring]

Hallo Bert,

Ich habe so eine Vermutung. Deshalb habe ich ein zusätzliches Logging eingebaut. Wäre klasse, wenn du mir bei Gelegenheit ein neues Debuglog erstellen würdest.

[jahnbes]

Hallo copystring,
mache ich gern, geht aber erst am Sonntagabend. Ich werde dann die aktuelle dev installieren, das log auf debug setzen und die Instanz starten.
Am Montag früh werden wir dann sehen, was passiert ist und ich schicke Dir das log.
Ein schönes Wochenende, Gruss Bert.

[jahnbes]

Hallo copystring,
seit dem gestrigen (obigen) Restart läuft der Adapter ohne Fehler. Ich werde weiter beobachten, schicke Dir trotzdem schon mal das log-File von gestern:
roborock_log_20240121_0.txt
Gruß Bert.

[copystring]

Merkwürdig. Habe gar keinen Fehler behoben.

[jahnbes]

So, nun hat es leider wieder geknallt, offensichtlich 10:45 das erste mal (?).
Das heutige log-File bis dato ist hier:
roborock_log_20240122_0.txt
Gruß Bert und viel Erfolg bei der Fehlersuche!

[copystring]

Ah ja. Ich denke, dass ich den Fehler gefunden habe. Würdest du die neue dev nochmal testen und ein neues Debuglog anhängen? Auch dann, wenn es keinen Fehler gibt. Wenn Roborock keine Antwort auf die Abfrage von HomeData gibt, dann sollte im Log homedata failed to download als Warnung auftauchen. Das sollte nicht allzu oft auftauchen.

[jahnbes]

Mach ich sofort und schicke Dir das Logfile morgen früh.
Viele Grüße, Bert.

[jahnbes]

Guten Morgen, copystring,
bis jetzt läuft der Adapter ohne Fehlermeldung. Ich schicke Dir hier, wie gewünscht, das log-debug-file von gestern. Heute lasse ich ihn noch etwas laufen, wenn keine Fehler auftritt, und schicke Dir dann das aktuelle log-file.
roborock_log_20240122_1.txt
Viele Grüße, Bert.

[jahnbes]

Hallo copystring,
unten das log-debug-file mit Stand von jetzt (16:44). Dein von Dir angekündigtes "Log homedata failed to download" taucht relativ häufig aus, im Moment sogar alle 10 Sekunden!
`
roborock.0
2024-01-23 19:03:05.227 warn homedata failed to download

roborock.0
2024-01-23 19:02:55.174 warn homedata failed to download

roborock.0
2024-01-23 19:02:45.183 warn homedata failed to download

roborock.0
2024-01-23 19:02:35.181 warn homedata failed to download

roborock.0
2024-01-23 19:02:25.179 warn homedata failed to download

roborock.0
2024-01-23 19:02:15.183 warn homedata failed to download

roborock.0
2024-01-23 19:02:05.181 warn homedata failed to download

roborock.0
2024-01-23 19:01:55.177 warn homedata failed to download

roborock.0
2024-01-23 19:01:45.192 warn homedata failed to download

roborock.0
2024-01-23 19:01:35.176 warn homedata failed to download

roborock.0
2024-01-23 19:01:25.185 warn homedata failed to download

roborock.0
2024-01-23 19:01:15.190 warn homedata failed to download

roborock.0
2024-01-23 19:01:05.172 warn homedata failed to download
`
Der Adapter aber läuft seit gestern ohne Fehler durch.
Viele Grüße, Bert.
(Ich musste das File zippen, da es sonst zur groß für dieses Forum wäre.)
roborock_log_20240123_1.zip
Ich hoffe, das ist ok so.
Kann ich das debug wieder abschalten?

[copystring]

Ob du das abschalten kannst, weiß ich nicht. Das kommt darauf an, ob sich das irgendwann wieder beruhigt. Geht die Warnung denn irgendwann weg oder wird HomeData danach nie wieder aktualisiert? Reicht es, den Adapter neu zu starten, damit HomeData wieder sauber geholt wird?

[jahnbes]

Nach einer Weile der Ruhe kommt das "warn homedata failed to download" leider seit 10:54 Uhr wieder alle 10 Sekunden. Dem voraus ging ein reconnect zum MQTT:
`roborock.0 2024-01-24 10:54:45.023 warn homedata failed to download

roborock.0 2024-01-24 10:50:22.247 warn MQTT connection reconnect.

roborock.0 2024-01-24 10:50:22.245 warn MQTT connection close.

roborock.0 2024-01-24 10:50:22.209 warn Reconnecting mqtt client!`
Ich werde beobachten und berichten, ob und wann sich der Adapter von selbst beruhigt, Gruß Bert.
roborock_log_20240124_1.txt

[jahnbes]

Noch eine Beobachtung:
Alle drei Stunden macht der Adapter ein erfolgreiches (?!) reconnect mqtt:

`2024-01-24 10:50:22.247 - �[33mwarn�[39m: roborock.0 (675) MQTT connection reconnect.

2024-01-24 13:50:22.248 - �[33mwarn�[39m: roborock.0 (675) MQTT connection reconnect.

2024-01-24 16:50:22.252 - �[33mwarn�[39m: roborock.0 (675) MQTT connection reconnect.`

Das erste am Tag war 10:50 Uhr,dann alle drei Stunden. Nach diesem ersten reconnect um 10:50 Uhr ging es los mit "warn homedata failed to download" für den Rest des Tages, das hat sich auch 13:50 Uhr und 16:50 Uhr nicht geändert und hält auch jetzt noch an.
Das gesamte log, teilweise ohne debug:
roborock_log_20240124_2.txt
Ich habe es auch als Excel-File da, falls Dir das mehr hilft.
Viele Grüße, Bert.

[jahnbes]

Hallo Copystring,

hier ein Zwischen- oder End(?)bericht. Seit gestern, Dienstag, den 24.1.24 23:30:46 Uhr ist Ruhe mit „homedata failed to download“ alle 10 Sekunden.
Es ist kein Grund für das Verschwinden dieser warnings für mich erkennbar. Ich habe nichts Neues installiert und auch den Adapter nicht neu gestartet.

Der Zeitablauf ist ungefähr:

24.1.24 22:50: letztes “MQTT connection reconnect” vor dem letzten “ homedata failed to download“ (23:30)
24.1.24 23:30: letztes „homedata failed to download“
25.1.24 01:50: nächstes “MQTT connection reconnect”
25.1.24 04:50: nächstes “MQTT connection reconnect”
25.1.24 07:50: nächstes “MQTT connection reconnect”

Ich schicke Dir trotzdem das komplette logfile von gestern und das von heute (bis jetzt) mit (teilweise ohne debug-Einstellung).

roborock_log_20240124_3.txt
und
roborock_log_20240125_1.txt

Besteht Hoffnung? Wenn Du noch was brauchst, melde Dich bitte.

Viele Grüße, Bert.

[jahnbes]

Zu früh gefreut, seit 10:56 Uhr ist alles wieder beim Alten...:

`2024-01-25 10:50:22.210 - warn: roborock.0 (675) Reconnecting mqtt client!

2024-01-25 10:50:22.242 - warn: roborock.0 (675) MQTT connection close.

2024-01-25 10:50:22.243 - warn: roborock.0 (675) MQTT connection reconnect.

2024-01-25 10:53:58.692 - info: admin.0 (7747) ==> Connected system.user.admin from 192.168.178.27

2024-01-25 10:54:01.731 - info: admin.0 (7747) <== Disconnect system.user.admin from 192.168.178.27 admin

...
2024-01-25 10:56:57.682 - warn: roborock.0 (675) homedata failed to download

2024-01-25 10:57:07.683 - warn: roborock.0 (675) homedata failed to download`

[jahnbes]

26. Januar 2024 09:26:
    Nun ist wieder Ruhe mit "homedata failed to download", aber das gleich Verhalten wie letzte Nacht, auch diesmal der Umschwung, d.h. das Verschwinden von "homedata failed to download" auch wieder 23:30 Uhr!
    Was steckt da dahinter?
    Mal sehe, ob der Fehler auch wieder gegen 11:00 Uhr erscheint.
    Viele Grüße, Bert.

[jahnbes]

26. Januar 2024 12:02:
    Es ist alles wieder beim Alten:
    2024-01-26 11:00:09.451 - warn: roborock.0 (675) homedata failed to download
    usw....

[copystring]

Vielleicht Nacht der Adapter zu viele Anfragen in 24 Stunden. Ich könnte das mal reduzieren. Wäre aber trotzdem merkwürdig. Ich könnte den Fehler bei mir nie erzeugen.

[jahnbes]

Bin ich der Einzige, bei dem dieser Fehler auftritt, oder melden ihn noch andere Nutzer? Liegt es am Typ des Roborock?
Sag mir bitte, wenn ich eine neue dev installieren soll.
Viele Grüße, Bert.

[copystring]

Ich konnte die Logs noch nicht prüfen, aber deine Beobachtungen sind sehr hilfreich. Ich habe jetzt mal in der dev den Intervall zum Aktualisieren von HomeData doppelt so lang gemacht. Wenn es wirklich eine Begrenzung der Anfrage in 24 Stunden ist, dann würde der Fehler sehr wahrscheinlich erst nach 6 anstatt 3 Stunden auftauchen.

[jahnbes]

In den letzten 24 h das gleiche Verhalten, wie bisher (alte dev):
26.1.24 23:31 Uhr: letztmalig "homedata failed to download"
27.1.24 10:47 Uhr erstmalig wieder "homedata failed to download"
Ich werde jetzt Deine neue dev installieren und berichten.
Allerdings sehe ich zwischen 26.1.24 23:31 Uhr und 27.1.24 10:47 Uhr keinen Abstand, der irgendwie an ein 3-Stunden-Intervall erinnert?
Gruß Bert.
27.1.24 12:16 Uhr: Fehler "homedata failed to download" läuft trotz neuer dev unbeeindruckt weiter so im 10 sec Rhythmus

[copystring]

Bin ich der Einzige, bei dem dieser Fehler auftritt, oder melden ihn noch andere Nutzer?

Nein. Der Einzige bist du nicht, nur weiß ich nicht, wie der Fehler zu reproduzieren ist. Bei mir läuft die aktuellste dev seit einigen Tagen stabil.

[jahnbes]

In den letzten 24 h das geändertes Verhalten, (neue dev ist vorher installiert):
27.1.24 23:32 Uhr: letztmalig "homedata failed to download"
28.1.24 20:36 Uhr erstmalig wieder "homedata failed to download"
Der Zeitraum zwischen Fehler weg und wieder da hat sich auf 21 h verdoppelt, vorher waren es ca. 11h.
Gruß Bert.

[copystring]

Okay. Genau das habe ich mir gedacht. Also ist meine Vermutung bestätigt. Es scheint so, als ist die Anzahl der Anfragen in den letzten 24 Stunden begrenzt.
Ursprünglich wurde HomeData alle 60 Sekunden geholt. Mit der dev, die du getestet hast, alle 120 Sekunden. Das scheint fast auszureichen.
In der neuen dev von gerade eben ist das Intervall fest auf 180 Sekunden eingestellt und nicht mehr konfigurierbar. Ich bin zuversichtlich, dass es jetzt laufen wird.

[jahnbes]

Gut, Football ist vorbei, ich installiere die neue dev mit 180 sec jetzt und wir sehen, was passiert.
Viele Grüße, Bert.

[jahnbes]

Heureka, den ganzen gestrigen Tag und auch den heutigen (30. Januar 2024) ist bis dato (08:10:36) "homedata failed to download" nicht mehr aufgetaucht! Ich werde weiter beobachten.
Viele Grüße, Bert.

[copystring]

Hallo Bert,
super! Dann können wir das hier abschließen.
Danke für das gründliche prüfen der Protokolle! Ich hatte dafür wirklich keine Zeit und das hat es mir überhaupt möglich gemacht, das Problem zu lösen.

Viele Grüße Felix