[Aufruf] IKEA-Trådfri Adapter testen
-
Hallo AlCalzone,
Nur dass ich dich richtig verstehe, du willst 24 Lampen synchron ihre Farbe wechseln lassen? Könnte mir höchstens vorstellen per Skript zeitgesteuert die Datenpunkte zu setzen und zu hoffen, dass das Gateway damit klarkommt `
So in der Art.
Ich möchte dass sich die Lichtfarbe von tagsüber kaltweiß in den Abendstunden immer mehr ins warmweiß verschiebt. Ähnlich der Grudhelleigkeit bei HM Dimmern, die ich über einen zweiten virtuellen Kanal nachts auf 50% senke.
Hast du mal geschaut, ob du unter dem Namen das Gateway pingen kannst? `
leider nein. Ich arbeite zu 99% mit den IP-Adressen, die vom Gateway kannte ich nicht auswendig
Verbindungswiederherstellung und ähnliche Späße sind leider noch nicht drin, das wird mit der Zeit kommen. `
keine Hektik. Ist das Problem denn bekannt / oder üblich?Ist mir gestern noch einmal passiert, und jetzt wo ich das tippe, probiere ich es auch erstmalig heute:
Die Fernbedienung ändert die beiden Birnen nicht synchron und nach paarmal schalten ist die Verbindung Gateway -> ioBroker ganz weg.
Schalten über ioBroker geht weiterhin, die Rückmeldung fehlt jedoch.
Gruß
Rainer
-
Ich möchte dass sich die Lichtfarbe von tagsüber kaltweiß in den Abendstunden immer mehr ins warmweiß verschiebt. `
Das klingt wie das, was https://justgetflux.com/ macht. Nur mit Lampen statt PC-Bildschirmen. Das fährt mit der Farbtemperatur quasi eine Sinus-Kurve ab, wobei Werte über einem Grenzwert (tagsüber) konstant gehalten werden.Du könntest ein Skript basteln, das alle 5-10 Minuten einen Trigger ausführt. Dieser müsste je nach aktueller Uhrzeit die Soll-Farbtemperatur zwischen 0 und 100% einstellen. Wenn die Schritte klein genug sind, solltest du das gar nicht merken.
Die Lampen haben einen Parameter, der das Zeitintervall einer Zustandsänderung vorgibt. Der ist aktuell auf wenige Sekunden hartcodiert, müsste man mal testen, was hier die Grenzen sind. Vielleicht geht das ja auch.
keine Hektik. Ist das Problem denn bekannt / oder üblich? `
Sagen wir mal so: Da ich bisher null Code zur Verbindungswiederherstellung oder erneutem Senden von verlorenen Paketen implementiert habe, hätte es mich gewundert, wenn nicht ab und zu mal ein Neustart des Adapters nötig wäre.Bei mir ist es auch schon öfters vorgekommen, dass beim Schalten über die Fernbedienung eine Lampe nicht sofort reagiert hat, sodass die dann gegenläufig an und ausgegangen sind.
nach paarmal schalten ist die Verbindung Gateway -> ioBroker ganz weg.
Schalten über ioBroker geht weiterhin, die Rückmeldung fehlt jedoch. `
Interessant. Sollten wir mal beobachten. Wenn das in zukünftigen, stabileren Versionen immer noch auftritt muss ich mir das mal genau anschauen. -
Das fährt mit der Farbtemperatur quasi eine Sinus-Kurve ab, wobei Werte über einem Grenzwert (tagsüber) konstant gehalten werden…Du könntest ein Skript basteln, das alle 5-10 Minuten einen Trigger ausführt. Dieser müsste je nach aktueller Uhrzeit die Soll-Farbtemperatur zwischen 0 und 100% einstellen. Wenn die Schritte klein genug sind, solltest du das gar nicht merken. `
Ja so in der Art schwebt es mir vor.Die Lampen haben einen Parameter, der das Zeitintervall einer Zustandsänderung vorgibt. Der ist aktuell auf wenige Sekunden hartcodiert, `
Das erklärt dann auch, warum mit der Fernbedienung drei Befehle unmittelbar hinereinander nicht funktionieren, sondern nur mit Pause.Hatte gehofft dass das an der FB liegt und über ioBroker besser läuft.
Gruß
Rainer
-
Die Lampen haben einen Parameter, der das Zeitintervall einer Zustandsänderung vorgibt. Der ist aktuell auf wenige Sekunden hartcodiert, `
Das erklärt dann auch, warum mit der Fernbedienung drei Befehle unmittelbar hinereinander nicht funktionieren, sondern nur mit Pause.Hatte gehofft dass das an der FB liegt und über ioBroker besser läuft. `
Mit hartcodiert meinte ich im Adapter und in der App. Wie die FB es regelt weiß ich nicht, ich vermute aber ähnlich.Musst du beim Schalten per ioBroker die gleichen Pausen einlegen bzw. würde dir eine variable Übergangsdauer was bringen?
Btw, in der CoAP-Schicht ist wiederholtes Senden von verlorenen Nachrichten jetzt implementiert. Kurzzeitige Verbindungsaussetzer in Richtung ioBroker ==> Gateway können jetzt überbrückt werden. Update per:
cd /opt/iobroker sudo npm install https://github.com/AlCalzone/node-coap-client/tarball/master --productionoder Adapter neu installieren (gibt aber keine neue Version).
-
Musst du beim Schalten per ioBroker die gleichen Pausen einlegen `
Im Moment "steuere" ich noch über die Objekte. Bis ich da einen neuen Wert eingehämmert habe, dürfte die Lag-Time verstrichen sein.Kurzzeitige Verbindungsaussetzer in Richtung ioBroker ==> Gateway können jetzt überbrückt werden. `
Ich habe ja die Probleme in die andere RichtungGruß
Rainer
-
Ich habe ja die Probleme in die andere Richtung `
Ich weiß, das erneute Senden ist aber Teil der Spezifikation, musste daher eh irgendwann rein. Und es könnte sein, dass das irgendwie einen Einfluss auf dein Problem hat. Ganz zu Anfang hat mich das Gateway auch schnell wieder vergessen, weil ich keine Bestätigungspakete zurückgeschickt habe.Sofern du dein Problem aber gezielt provozieren kannst, gibt es etwas was du tun könntest:
sudo tcpdump -w tradfri.dump udp port 5684schneidet den gesamten Traffic auf Port 5684 mit und speichert ihn in der Datei tradfri.dump. Um dein Problem zu analysieren, brauche ich die sowie deinen Security-Code vom Gateway. Wichtig ist, dass du beginnst zu loggen bevor der Adapter gestartet wird, sonst kann ich mit dem Traffic nichts anfangen (verschlüsselt).
-
Ich helfe ja gerne
, aber…Sofern du dein Problem aber gezielt provozieren kannst `
Habe ich bisher noch nicht getestet.…und was bedeutet das für einen alten dummen Mann:
@AlCalzone:speichert ihn in der Datei tradfri.dump. …Wichtig ist, dass du beginnst zu loggen bevor der Adapter gestartet wird, `
:?: :?:Anfangen zu loggen und dann den Adapter starten und dann irgendwie die Kommunikation kaputt bekommen? (oder einfach nur ein paar Stunden warten?)
Wieviel wird das denn?
und wo ist diese Datei?
Gruß
Rainer
-
…und was bedeutet das für einen alten dummen Mann:
@AlCalzone:speichert ihn in der Datei tradfri.dump. …Wichtig ist, dass du beginnst zu loggen bevor der Adapter gestartet wird, `
:?: :?:1.) Anfangen zu loggen und dann den Adapter starten und dann irgendwie die Kommunikation kaputt bekommen? (oder einfach nur ein paar Stunden warten?)
2.) Wieviel wird das denn?
3.) und wo ist diese Datei?r `
Ok, nochmal langsam:1.) ja. Du hattest mal gemeint, du schaffst das durch mehrmaliges schnelles Betätigen der Fernbedienung, dass keine Infos mehr zurück an den Adapter kommen. Ansonsten wirst du vermutlich warten müssen, bis etwas kaputt geht.
2.) hängt ganz von der übertragenen Datenmenge ab. Ein paar wenige MB könnten es schon werden.
3.) Mit dem Befehl gibst du hinter -w an, wo der Log hin soll, in diesem Fall heißt die Datei "tradfri.dump" und ist in dem Ordner wo du den Befehl ausführst.
sudo tcpdump -w tradfri.dump udp port 5684Auf dem Pi ist das direkt nach Login üblicherweise "/home/pi".
-
Da habe ich mich wohl irgendwann nicht deutlich ausgedrückt, oder mein Hirn ist schon so durchlöchert, dass ich mich da nicht mehr dran erinnere.
@AlCalzone:Du hattest mal gemeint, du schaffst das durch mehrmaliges schnelles Betätigen der Fernbedienung, dass keine Infos mehr zurück an den Adapter kommen `
Aber: das könnte auch ein Ansatz sein, werde ich heute Abend zu Hause versuchen zu provozieren.und ist in dem Ordner wo du den Befehl ausführst. `
ok, dann bis heute Abend.Gruß
Rainer
-
Da habe ich mich wohl irgendwann nicht deutlich ausgedrückt, oder mein Hirn ist schon so durchlöchert, dass ich mich da nicht mehr dran erinnere.
Vielleicht hab ich dich auch falsch verstanden:> Die Fernbedienung ändert die beiden Birnen nicht synchron und nach paarmal schalten ist die Verbindung Gateway -> ioBroker ganz weg.Schalten über ioBroker geht weiterhin, die Rückmeldung fehlt jedoch. `
ok, dann bis heute Abend. `
Kein Stress, komme erst morgen dazu. -
Hallo AlCalzone,
Die Fernbedienung ändert die beiden Birnen nicht synchron und nach paarmal schalten ist die Verbindung Gateway -> ioBroker ganz weg.
Schalten über ioBroker geht weiterhin, die Rückmeldung fehlt jedoch. `
Das lässt sich definitiv provozierenund jetzt…
linaro@linaro-alip:~$ sudo tcpdump -w tradfri.dump udp port 5684 sudo: tcpdump: Kommando nicht gefundenWat nu?
etwa:
sudo apt-get install tcpdump:?: :?:
Hab ich gemacht
…und mit CTRL-C wieder raus nach dem er endlich wieder hing.
linaro@linaro-alip:~$ sudo tcpdump -w tradfri.dump udp port 5684 tcpdump: listening on eth0, link-type EN10MB (Ethernet), capture size 262144 bytes ^C278 packets captured 278 packets received by filter 0 packets dropped by kernelRest kommt mit PN
Gruß
Rainer
-
Nabend, erster Test war direkt positiv. Ich werde das Ganze am WE mal in VIS einbinden. Hab im Moment 3 Lampen über eine Fernbedienung verbunden. Nächste Woche kauf ich dann noch eine und ein paar Lampen. Danke!!!
-
In deinem Dump ist recht gut zu erkennen, was passiert. Ich weiß nur noch nicht, warum.
Der Fehler scheint tatsächlich in der Richtung ioBroker => Gateway aufzutreten. Auf einen Schlag werden keine Bestätigungen mehr über die empfangenen Pakete gesendet. Dann hört das Gateway nach kurzer Zeit auf zu senden, weil es denkt, da hört keiner mehr zu.
Gibts irgendwelche Fehler im Log?
Ich würde dich mal bitten, die aktuelle Debug-Version zu installieren:
cd /opt/iobroker sudo npm install https://github.com/AlCalzone/iobroker.tradfri/tarball/debug --productionund den Adapter im Admin-Menü auf "debug" output zu stellen.
Damit sollte die CoAP- und DTLS-Schicht aktualisiert sein, ein paar mehr Infos von sich geben und im ioBroker-Log landen.
cd /opt/iobroker/log sudo cat iobroker.2017-07-XX.log | grep tradfri.0 | grep STDOUTgibt dir nur die logs der Transport-Schichten aus und lässt den unnötigen Kram weg. Oder eben mit Filter "STDOUT" im Admin-Menü.
-
Gibts irgendwelche Fehler im Log? `
Nope, aber es hat sich diesmal einiges später selbst geheilt (!?)Daher hatte ich schon befürchtet, dass ich zu früh beendet hatte.
Ich würde dich mal bitten, die aktuelle Debug-Version zu installieren:…und den Adapter im Admin-Menü auf "debug" output zu stellen. `
Gerne, wenn ich mal aus dem Büro rauskommeGruß
Rainer
-
Hi,
ich habe hier zwei Ikea- Lampen mit Weissschattierungen im Einsatz. Gesteuert werden sollen diese über Alexa.
Im Adapter werden die Lampen angezeigt und sind auch über die Cloud in Alexa sichtbar (ein einer Gruppe zusammengefasst).
Leider läuft nach einer gewissen Zeit etwas schief und die Lampen beginnen ein Eigenleben zu entwickeln
Ich habe im Cloudadapter beide Lampen mit Brightness Start 100% freigegeben. Das gleiche mit der Farbtemp, Startwert 65%.
Schalte ich nun ein paar mal über Alexa ändert sich zufällig die Helligkeit, obwohl ich nur den Befehl AN verwende, ohne Dimmen.
Auch die Farbtemperatur passt irgendwann nicht mehr, beide Lampen haben dann eine Unterschiedliche.
Das beste ist jedoch das folgende Eigenleben: Die Lampen gehen kurz aus und wieder an, ändern die Helligkeit und oder Farbtemperatur. Und das in einem Abstand von ein paar Sekunden, ohne jegliche Aktion meinerseits…
Strange. Wenn ich es schaffe, lege ich heute abend auch mal ein Log an bzw. einen Dump.
Reines Einschalten, ohne Farbtemp in der Gruppe scheint zu gehen.
Ziel ist es aber, ähnlich wie beim Kollegen die Farbtemepratur auch zu ändern, und zwar abhängig von morgens, mittags und abends.
Gruß,
Moses123
-
Schalte ich nun ein paar mal über Alexa ändert sich zufällig die Helligkeit, obwohl ich nur den Befehl AN verwende, ohne Dimmen.
Auch die Farbtemperatur passt irgendwann nicht mehr, beide Lampen haben dann eine Unterschiedliche.
Das beste ist jedoch das folgende Eigenleben: Die Lampen gehen kurz aus und wieder an, ändern die Helligkeit und oder Farbtemperatur. Und das in einem Abstand von ein paar Sekunden, ohne jegliche Aktion meinerseits… `
Ööööööh, ja Log + Dump bitte -
So, anbei schon mal ein Ausschnitt aus dem Log, wenn die Lampen von alleine an und aus gehen. Im Log sieht das so aus, dass nicht registriert wird, ob die Meldung vom Gate angenommen wurde. Da es wohl eher zufällig ist, was da gesendet wird, kommen auch seltsamste Effekte dabei raus.
tradfri.0 2017-07-28 20:41:44.082 debug STDOUT > retransmitting message 42, try #5 tradfri.0 2017-07-28 20:41:23.478 debug STDOUT > retransmitting message 43, try #5 tradfri.0 2017-07-28 20:41:21.108 debug STDOUT > retransmitting message 41, try #5 tradfri.0 2017-07-28 20:41:20.686 debug STDOUT > retransmitting message 3d, try #5 javascript.0 2017-07-28 20:41:11.528 info script.js.TEST.Arbeitszimmer_Lichtwechsel_2: registered 1 subscription and 0 schedules javascript.0 2017-07-28 20:41:11.527 info script.js.TEST.Arbeitszimmer_Lichtwechsel_2: test javascript.0 2017-07-28 20:41:11.338 info Start javascript script.js.TEST.Arbeitszimmer_Lichtwechsel_2 javascript.0 2017-07-28 20:41:11.274 info Stop script script.js.TEST.Arbeitszimmer_Lichtwechsel(1) tradfri.0 2017-07-28 20:41:11.205 debug STDOUT > retransmitting message 40, try #5 tradfri.0 2017-07-28 20:41:10.548 debug STDOUT > retransmitting message 3f, try #5 tradfri.0 2017-07-28 20:41:07.930 debug STDOUT > retransmitting message 3e, try #5 tradfri.0 2017-07-28 20:41:01.255 debug STDOUT > retransmitting message 42, try #4 tradfri.0 2017-07-28 20:40:59.860 debug STDOUT > retransmitting message 3c, try #5 tradfri.0 2017-07-28 20:40:58.549 debug STDOUT > retransmitting message 3a, try #5 tradfri.0 2017-07-28 20:40:51.299 debug STDOUT > retransmitting message 43, try #4 tradfri.0 2017-07-28 20:40:42.049 debug STDOUT > retransmitting message 3b, try #5 tradfri.0 2017-07-28 20:40:39.842 debug STDOUT > retransmitting message 42, try #3 tradfri.0 2017-07-28 20:40:37.777 debug STDOUT > retransmitting message 41, try #4 tradfri.0 2017-07-28 20:40:35.208 debug STDOUT > retransmitting message 43, try #3 javascript.0 2017-07-28 20:40:35.205 info script.js.TEST.Arbeitszimmer_Lichtwechsel_1: registered 1 subscription and 0 schedules javascript.0 2017-07-28 20:40:35.204 info script.js.TEST.Arbeitszimmer_Lichtwechsel_1: test javascript.0 2017-07-28 20:40:35.054 info Start javascript script.js.TEST.Arbeitszimmer_Lichtwechsel_1 javascript.0 2017-07-28 20:40:35.000 info Stop script script.js.TEST.Arbeitszimmer_Lichtwechsel tradfri.0 2017-07-28 20:40:34.940 debug STDOUT > retransmitting message 3d, try #4 tradfri.0 2017-07-28 20:40:32.930 debug STDOUT > retransmitting message 40, try #4 tradfri.0 2017-07-28 20:40:32.607 debug STDOUT > retransmitting message 3f, try #4 tradfri.0 2017-07-28 20:40:29.133 debug STDOUT > retransmitting message 42, try #2 tradfri.0 2017-07-28 20:40:28.772 debug STDOUT > retransmitting message 3e, try #4 tradfri.0 2017-07-28 20:40:27.161 debug STDOUT > retransmitting message 43, try #2 tradfri.0 2017-07-28 20:40:23.774 debug STDOUT > retransmitting message 42, try #1 tradfri.0 2017-07-28 20:40:23.134 debug STDOUT > retransmitting message 43, try #1 javascript.0 2017-07-28 20:40:22.932 info script.js.TEST.Arbeitszimmer_Lichtwechsel(1): registered 1 subscription and 0 schedules javascript.0 2017-07-28 20:40:22.931 info script.js.TEST.Arbeitszimmer_Lichtwechsel(1): test javascript.0 2017-07-28 20:40:22.763 info Start javascript script.js.TEST.Arbeitszimmer_Lichtwechsel(1) tradfri.0 2017-07-28 20:40:21.121 debug STDOUT > remembering request with token 964c9d86 tradfri.0 2017-07-28 20:40:21.105 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:40:21.103 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=false; val=0 tradfri.0 2017-07-28 20:40:21.102 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=0, ack=false, ts=1501267221090, q=0, from=system.adapter.cloud.0, lc=1501267221090 tradfri.0 2017-07-28 20:40:21.098 debug STDOUT > remembering request with token 964c9d85 tradfri.0 2017-07-28 20:40:21.092 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:40:21.091 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=false; val=0 javascript.0 2017-07-28 20:40:21.094 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:40:21.090 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=0, ack=false, ts=1501267221085, q=0, from=system.adapter.cloud.0, lc=1501267221085 cloud.0 2017-07-28 20:40:21.078 warn Unknown applianceId: Arbeitszimmer tradfri.0 2017-07-28 20:40:16.108 debug STDOUT > retransmitting message 41, try #3 tradfri.0 2017-07-28 20:40:13.920 debug STDOUT > retransmitting message 3c, try #4 tradfri.0 2017-07-28 20:40:13.791 debug STDOUT > retransmitting message 40, try #3 tradfri.0 2017-07-28 20:40:13.637 debug STDOUT > retransmitting message 3f, try #3 tradfri.0 2017-07-28 20:40:13.232 debug STDOUT > retransmitting message 3a, try #4 tradfri.0 2017-07-28 20:40:12.063 debug STDOUT > retransmitting message 3d, try #3 tradfri.0 2017-07-28 20:40:09.192 debug STDOUT > retransmitting message 3e, try #3 tradfri.0 2017-07-28 20:40:05.271 debug STDOUT > retransmitting message 41, try #2 tradfri.0 2017-07-28 20:40:05.260 debug STDOUT > retransmitting message 3b, try #4 tradfri.0 2017-07-28 20:40:04.221 debug STDOUT > retransmitting message 40, try #2 tradfri.0 2017-07-28 20:40:04.149 debug STDOUT > retransmitting message 3f, try #2 tradfri.0 2017-07-28 20:40:00.621 debug STDOUT > retransmitting message 3d, try #2 tradfri.0 2017-07-28 20:39:59.852 debug STDOUT > retransmitting message 41, try #1 tradfri.0 2017-07-28 20:39:59.435 debug STDOUT > retransmitting message 40, try #1 tradfri.0 2017-07-28 20:39:59.402 debug STDOUT > retransmitting message 3f, try #1 tradfri.0 2017-07-28 20:39:59.399 debug STDOUT > retransmitting message 3e, try #2 tradfri.0 2017-07-28 20:39:57.144 debug STDOUT > remembering request with token 964c9d84 tradfri.0 2017-07-28 20:39:57.143 debug sending payload: {'3311':[{'5709':33135,'5710':27000,'5712':5}]} tradfri.0 2017-07-28 20:39:57.142 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=false; val=100 tradfri.0 2017-07-28 20:39:57.141 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=100, ack=false, ts=1501267197137, q=0, from=system.adapter.javascript.0, lc=1501267197137 tradfri.0 2017-07-28 20:39:57.043 debug STDOUT > remembering request with token 964c9d83 tradfri.0 2017-07-28 20:39:57.040 debug sending payload: {'3311':[{'5712':5,'5851':254}]} tradfri.0 2017-07-28 20:39:57.039 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=false; val=254 tradfri.0 2017-07-28 20:39:57.037 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=254, ack=false, ts=1501267197029, q=0, from=system.adapter.cloud.0, lc=1501267197029 javascript.0 2017-07-28 20:39:57.035 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Spät abends javascript.0 2017-07-28 20:39:57.033 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:39:57.028 debug STDOUT > remembering request with token 964c9d82 tradfri.0 2017-07-28 20:39:57.026 debug sending payload: {'3311':[{'5712':5,'5851':254}]} tradfri.0 2017-07-28 20:39:57.024 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=false; val=254 tradfri.0 2017-07-28 20:39:57.023 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=254, ack=false, ts=1501267197018, q=0, from=system.adapter.cloud.0, lc=1501267197018 cloud.0 2017-07-28 20:39:57.011 warn Unknown applianceId: Arbeitszimmer tradfri.0 2017-07-28 20:39:54.901 debug STDOUT > retransmitting message 3d, try #1 tradfri.0 2017-07-28 20:39:54.500 debug STDOUT > retransmitting message 3e, try #1 tradfri.0 2017-07-28 20:39:52.051 debug STDOUT > remembering request with token 964c9d81 tradfri.0 2017-07-28 20:39:52.048 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:39:52.047 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=false; val=0 tradfri.0 2017-07-28 20:39:52.046 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=0, ack=false, ts=1501267192038, q=0, from=system.adapter.cloud.0, lc=1501267192038 tradfri.0 2017-07-28 20:39:52.041 debug STDOUT > remembering request with token 964c9d80 tradfri.0 2017-07-28 20:39:52.040 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:39:52.039 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=false; val=0 tradfri.0 2017-07-28 20:39:52.038 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=0, ack=false, ts=1501267192034, q=0, from=system.adapter.cloud.0, lc=1501267192034 javascript.0 2017-07-28 20:39:52.042 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event cloud.0 2017-07-28 20:39:52.028 warn Unknown applianceId: Arbeitszimmer tradfri.0 2017-07-28 20:39:50.949 debug STDOUT > retransmitting message 3c, try #3 tradfri.0 2017-07-28 20:39:50.572 debug STDOUT > retransmitting message 3a, try #3 tradfri.0 2017-07-28 20:39:46.866 debug STDOUT > retransmitting message 3b, try #3 javascript.0 2017-07-28 20:39:46.818 info script.js.TEST.Arbeitszimmer_Lichtwechsel: registered 1 subscription and 0 schedules javascript.0 2017-07-28 20:39:46.817 info script.js.TEST.Arbeitszimmer_Lichtwechsel: test javascript.0 2017-07-28 20:39:46.661 info Start javascript script.js.TEST.Arbeitszimmer_Lichtwechsel javascript.0 2017-07-28 20:39:46.643 info Stop script script.js.TEST.Arbeitszimmer_Lichtwechsel tradfri.0 2017-07-28 20:39:39.463 debug STDOUT > retransmitting message 3c, try #2 tradfri.0 2017-07-28 20:39:39.239 debug STDOUT > retransmitting message 3a, try #2 tradfri.0 2017-07-28 20:39:37.664 debug STDOUT > retransmitting message 3b, try #2 tradfri.0 2017-07-28 20:39:33.719 debug STDOUT > retransmitting message 3c, try #1 tradfri.0 2017-07-28 20:39:33.564 debug STDOUT > retransmitting message 3a, try #1 tradfri.0 2017-07-28 20:39:33.057 debug STDOUT > retransmitting message 3b, try #1 tradfri.0 2017-07-28 20:39:30.848 debug STDOUT > remembering request with token 964c9d7f tradfri.0 2017-07-28 20:39:30.843 debug sending payload: {'3311':[{'5709':29033,'5710':27000,'5712':5}]} tradfri.0 2017-07-28 20:39:30.842 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=false; val=50 tradfri.0 2017-07-28 20:39:30.840 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=50, ack=false, ts=1501267170836, q=0, from=system.adapter.javascript.0, lc=1501257708952 tradfri.0 2017-07-28 20:39:30.753 debug STDOUT > remembering request with token 964c9d7e tradfri.0 2017-07-28 20:39:30.751 debug sending payload: {'3311':[{'5712':5,'5851':254}]} tradfri.0 2017-07-28 20:39:30.750 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=false; val=254 tradfri.0 2017-07-28 20:39:30.749 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=254, ack=false, ts=1501267170724, q=0, from=system.adapter.cloud.0, lc=1501267170724 tradfri.0 2017-07-28 20:39:30.731 debug STDOUT > remembering request with token 964c9d7d tradfri.0 2017-07-28 20:39:30.728 debug sending payload: {'3311':[{'5712':5,'5851':254}]} tradfri.0 2017-07-28 20:39:30.726 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=false; val=254 javascript.0 2017-07-28 20:39:30.733 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Tagsüber javascript.0 2017-07-28 20:39:30.729 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:39:30.724 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=254, ack=false, ts=1501267170719, q=0, from=system.adapter.cloud.0, lc=1501267170719 cloud.0 2017-07-28 20:39:30.712 warn Unknown applianceId: Arbeitszimmer tradfri.0 2017-07-28 20:39:18.261 debug state with id tradfri.0.L-65537.lightbulb.state updated: ack=true; val=false tradfri.0 2017-07-28 20:39:18.260 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.state val=false, ack=true, ts=1501267158242, q=0, from=system.adapter.tradfri.0, lc=1501267158239 tradfri.0 2017-07-28 20:39:18.259 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=true; val=1 tradfri.0 2017-07-28 20:39:18.259 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=1, ack=true, ts=1501267158242, q=0, from=system.adapter.tradfri.0, lc=1501267158242 tradfri.0 2017-07-28 20:39:18.257 debug state with id tradfri.0.L-65537.lightbulb.color updated: ack=true; val=63 tradfri.0 2017-07-28 20:39:18.257 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.color val=63, ack=true, ts=1501267158241, q=0, from=system.adapter.tradfri.0, lc=1501257713185 tradfri.0 2017-07-28 20:39:18.255 debug state with id tradfri.0.L-65537.lastSeen updated: ack=true; val=1501266759 tradfri.0 2017-07-28 20:39:18.254 debug inMem message tradfri.0.* tradfri.0.L-65537.lastSeen val=1501266759, ack=true, ts=1501267158240, q=0, from=system.adapter.tradfri.0, lc=1501266758301 tradfri.0 2017-07-28 20:39:18.252 debug state with id tradfri.0.L-65537.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:18.251 debug inMem message tradfri.0.* tradfri.0.L-65537.alive val=true, ack=true, ts=1501267158240, q=0, from=system.adapter.tradfri.0, lc=1501257459508 tradfri.0 2017-07-28 20:39:18.250 debug state with id tradfri.0.L-65537.lightbulb.state updated: ack=true; val=false tradfri.0 2017-07-28 20:39:18.249 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.state val=false, ack=true, ts=1501267158239, q=0, from=system.adapter.tradfri.0, lc=1501267158239 tradfri.0 2017-07-28 20:39:18.247 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=true; val=0 tradfri.0 2017-07-28 20:39:18.246 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=0, ack=true, ts=1501267158238, q=0, from=system.adapter.tradfri.0, lc=1501267157589 tradfri.0 2017-07-28 20:39:18.242 debug state with id tradfri.0.L-65537.lightbulb.color updated: ack=true; val=63 tradfri.0 2017-07-28 20:39:18.241 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.color val=63, ack=true, ts=1501267158236, q=0, from=system.adapter.tradfri.0, lc=1501257713185 tradfri.0 2017-07-28 20:39:18.237 debug state with id tradfri.0.L-65537.lastSeen updated: ack=true; val=1501266759 tradfri.0 2017-07-28 20:39:18.236 debug inMem message tradfri.0.* tradfri.0.L-65537.lastSeen val=1501266759, ack=true, ts=1501267158231, q=0, from=system.adapter.tradfri.0, lc=1501266758301 tradfri.0 2017-07-28 20:39:18.234 debug state with id tradfri.0.L-65537.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:18.233 debug inMem message tradfri.0.* tradfri.0.L-65537.alive val=true, ack=true, ts=1501267158217, q=0, from=system.adapter.tradfri.0, lc=1501257459508 tradfri.0 2017-07-28 20:39:18.227 debug STDOUT > sending ACK for 9235 tradfri.0 2017-07-28 20:39:18.224 debug STDOUT > received message: ID=37429, token=964c9d46 tradfri.0 2017-07-28 20:39:18.218 debug STDOUT > sending ACK for 9234 tradfri.0 2017-07-28 20:39:18.214 debug STDOUT > received message: ID=37428, token=964c9d46 tradfri.0 2017-07-28 20:39:17.707 debug state with id tradfri.0.L-65538.lightbulb.state updated: ack=true; val=true tradfri.0 2017-07-28 20:39:17.706 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.state val=true, ack=true, ts=1501267157687, q=0, from=system.adapter.tradfri.0, lc=1501267152785 tradfri.0 2017-07-28 20:39:17.705 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=true; val=0 tradfri.0 2017-07-28 20:39:17.704 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=0, ack=true, ts=1501267157686, q=0, from=system.adapter.tradfri.0, lc=1501267157598 tradfri.0 2017-07-28 20:39:17.703 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=true; val=50 tradfri.0 2017-07-28 20:39:17.702 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=50, ack=true, ts=1501267157685, q=0, from=system.adapter.tradfri.0, lc=1501257708952 tradfri.0 2017-07-28 20:39:17.701 debug state with id tradfri.0.L-65538.lastSeen updated: ack=true; val=1501264887 tradfri.0 2017-07-28 20:39:17.700 debug inMem message tradfri.0.* tradfri.0.L-65538.lastSeen val=1501264887, ack=true, ts=1501267157683, q=0, from=system.adapter.tradfri.0, lc=1501264886784 javascript.0 2017-07-28 20:39:17.694 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:39:17.690 debug state with id tradfri.0.L-65538.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:17.689 debug inMem message tradfri.0.* tradfri.0.L-65538.alive val=true, ack=true, ts=1501267157678, q=0, from=system.adapter.tradfri.0, lc=1501257521430 tradfri.0 2017-07-28 20:39:17.688 debug state with id tradfri.0.L-65537.lightbulb.state updated: ack=true; val=true tradfri.0 2017-07-28 20:39:17.687 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.state val=true, ack=true, ts=1501267157670, q=0, from=system.adapter.tradfri.0, lc=1501267152781 tradfri.0 2017-07-28 20:39:17.684 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=true; val=0 tradfri.0 2017-07-28 20:39:17.682 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=0, ack=true, ts=1501267157664, q=0, from=system.adapter.tradfri.0, lc=1501267157589 tradfri.0 2017-07-28 20:39:17.680 debug state with id tradfri.0.L-65537.lightbulb.color updated: ack=true; val=63 tradfri.0 2017-07-28 20:39:17.679 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.color val=63, ack=true, ts=1501267157654, q=0, from=system.adapter.tradfri.0, lc=1501257713185 tradfri.0 2017-07-28 20:39:17.677 debug state with id tradfri.0.L-65537.lastSeen updated: ack=true; val=1501266759 tradfri.0 2017-07-28 20:39:17.676 debug inMem message tradfri.0.* tradfri.0.L-65537.lastSeen val=1501266759, ack=true, ts=1501267157649, q=0, from=system.adapter.tradfri.0, lc=1501266758301 tradfri.0 2017-07-28 20:39:17.670 debug STDOUT > sending ACK for 9233 tradfri.0 2017-07-28 20:39:17.663 debug STDOUT > received message: ID=37427, token=964c9d47 tradfri.0 2017-07-28 20:39:17.656 debug STDOUT > received ACK for 39, stopping retransmission... tradfri.0 2017-07-28 20:39:17.656 debug STDOUT > received message: ID=57, token=964c9d7c tradfri.0 2017-07-28 20:39:17.652 debug state with id tradfri.0.L-65537.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:17.651 debug inMem message tradfri.0.* tradfri.0.L-65537.alive val=true, ack=true, ts=1501267157641, q=0, from=system.adapter.tradfri.0, lc=1501257459508 tradfri.0 2017-07-28 20:39:17.642 debug STDOUT > sending ACK for 9232 tradfri.0 2017-07-28 20:39:17.635 debug STDOUT > received message: ID=37426, token=964c9d46 tradfri.0 2017-07-28 20:39:17.632 debug STDOUT > received ACK for 38, stopping retransmission... tradfri.0 2017-07-28 20:39:17.631 debug STDOUT > received message: ID=56, token=964c9d7b tradfri.0 2017-07-28 20:39:17.614 debug STDOUT > remembering request with token 964c9d7c tradfri.0 2017-07-28 20:39:17.612 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:39:17.611 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=false; val=0 tradfri.0 2017-07-28 20:39:17.609 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=0, ack=false, ts=1501267157598, q=0, from=system.adapter.cloud.0, lc=1501267157598 tradfri.0 2017-07-28 20:39:17.605 debug STDOUT > remembering request with token 964c9d7b tradfri.0 2017-07-28 20:39:17.598 debug sending payload: {'3311':[{'5712':5,'5851':0}]} tradfri.0 2017-07-28 20:39:17.597 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=false; val=0 javascript.0 2017-07-28 20:39:17.602 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:39:17.595 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=0, ack=false, ts=1501267157589, q=0, from=system.adapter.cloud.0, lc=1501267157589 cloud.0 2017-07-28 20:39:17.581 warn Unknown applianceId: Arbeitszimmer tradfri.0 2017-07-28 20:39:12.924 debug STDOUT > received ACK for 37, stopping retransmission... tradfri.0 2017-07-28 20:39:12.924 debug STDOUT > received message: ID=55, token=964c9d7a tradfri.0 2017-07-28 20:39:12.900 debug STDOUT > remembering request with token 964c9d7a tradfri.0 2017-07-28 20:39:12.898 debug sending payload: {'3311':[{'5709':29033,'5710':27000,'5712':5}]} tradfri.0 2017-07-28 20:39:12.898 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=false; val=50 tradfri.0 2017-07-28 20:39:12.897 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=50, ack=false, ts=1501267152894, q=0, from=system.adapter.javascript.0, lc=1501257708952 tradfri.0 2017-07-28 20:39:12.857 debug STDOUT > received ACK for 36, stopping retransmission... tradfri.0 2017-07-28 20:39:12.856 debug STDOUT > received message: ID=54, token=964c9d79 tradfri.0 2017-07-28 20:39:12.829 debug STDOUT > remembering request with token 964c9d79 tradfri.0 2017-07-28 20:39:12.827 debug sending payload: {'3311':[{'5709':29033,'5710':27000,'5712':5}]} tradfri.0 2017-07-28 20:39:12.826 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=false; val=50 tradfri.0 2017-07-28 20:39:12.825 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=50, ack=false, ts=1501267152808, q=0, from=system.adapter.javascript.0, lc=1501257708952 tradfri.0 2017-07-28 20:39:12.824 debug state with id tradfri.0.L-65538.lightbulb.state updated: ack=true; val=true tradfri.0 2017-07-28 20:39:12.823 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.state val=true, ack=true, ts=1501267152785, q=0, from=system.adapter.tradfri.0, lc=1501267152785 tradfri.0 2017-07-28 20:39:12.822 debug state with id tradfri.0.L-65538.lightbulb.brightness updated: ack=true; val=254 tradfri.0 2017-07-28 20:39:12.821 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.brightness val=254, ack=true, ts=1501267152784, q=0, from=system.adapter.tradfri.0, lc=1501267152698 tradfri.0 2017-07-28 20:39:12.819 debug state with id tradfri.0.L-65538.lightbulb.color updated: ack=true; val=50 tradfri.0 2017-07-28 20:39:12.818 debug inMem message tradfri.0.* tradfri.0.L-65538.lightbulb.color val=50, ack=true, ts=1501267152784, q=0, from=system.adapter.tradfri.0, lc=1501257708952 tradfri.0 2017-07-28 20:39:12.817 debug state with id tradfri.0.L-65538.lastSeen updated: ack=true; val=1501264887 tradfri.0 2017-07-28 20:39:12.816 debug inMem message tradfri.0.* tradfri.0.L-65538.lastSeen val=1501264887, ack=true, ts=1501267152783, q=0, from=system.adapter.tradfri.0, lc=1501264886784 tradfri.0 2017-07-28 20:39:12.805 debug state with id tradfri.0.L-65538.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:12.804 debug inMem message tradfri.0.* tradfri.0.L-65538.alive val=true, ack=true, ts=1501267152783, q=0, from=system.adapter.tradfri.0, lc=1501257521430 tradfri.0 2017-07-28 20:39:12.803 debug state with id tradfri.0.L-65537.lightbulb.state updated: ack=true; val=true tradfri.0 2017-07-28 20:39:12.802 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.state val=true, ack=true, ts=1501267152781, q=0, from=system.adapter.tradfri.0, lc=1501267152781 tradfri.0 2017-07-28 20:39:12.801 debug state with id tradfri.0.L-65537.lightbulb.brightness updated: ack=true; val=254 tradfri.0 2017-07-28 20:39:12.800 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.brightness val=254, ack=true, ts=1501267152780, q=0, from=system.adapter.tradfri.0, lc=1501267152690 javascript.0 2017-07-28 20:39:12.792 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Tagsüber javascript.0 2017-07-28 20:39:12.791 info script.js.TEST.Arbeitszimmer_Lichtwechsel: Event tradfri.0 2017-07-28 20:39:12.785 debug state with id tradfri.0.L-65537.lightbulb.color updated: ack=true; val=63 tradfri.0 2017-07-28 20:39:12.784 debug inMem message tradfri.0.* tradfri.0.L-65537.lightbulb.color val=63, ack=true, ts=1501267152777, q=0, from=system.adapter.tradfri.0, lc=1501257713185 tradfri.0 2017-07-28 20:39:12.783 debug state with id tradfri.0.L-65537.lastSeen updated: ack=true; val=1501266759 tradfri.0 2017-07-28 20:39:12.780 debug inMem message tradfri.0.* tradfri.0.L-65537.lastSeen val=1501266759, ack=true, ts=1501267152766, q=0, from=system.adapter.tradfri.0, lc=1501266758301 tradfri.0 2017-07-28 20:39:12.776 debug state with id tradfri.0.L-65537.alive updated: ack=true; val=true tradfri.0 2017-07-28 20:39:12.775 debug inMem message tradfri.0.* tradfri.0.L-65537.alive val=true, ack=true, ts=1501267152762, q=0, from=system.adapter.tradfri.0, lc=1501257459508 tradfri.0 2017-07-28 20:39:12.759 debug STDOUT > sending ACK for 9231 tradfri.0 2017-07-28 20:39:12.754 debug STDOUT > received message: ID=37425, token=964c9d47 tradfri.0 2017-07-28 20:39:12.752 debug STDOUT > received ACK for 35, stopping retransmission... tradfri.0 2017-07-28 20:39:12.751 debug STDOUT > received message: ID=53, token=964c9d78 tradfri.0 2017-07-28 20:39:12.746 debug STDOUT > sending ACK for 9230 tradfri.0 2017-07-28 20:39:12.737 debug STDOUT > received message: ID=37424, token=964c9d46 tradfri.0 2017-07-28 20:39:12.735 debug STDOUT > received ACK for 34, stopping retransmission... tradfri.0 2017-07-28 20:39:12.732 debug STDOUT > received message: ID=52, token=964c9d77 tradfri.0 2017-07-28 20:39:12.710 debug STDOUT > remembering request with token 964c9d78 -
Sieht so aus, als würden beim Szenenwechsel zu schnell hintereinander Nachrichten gesendet, so dass sich das Gateway verschluckt. Dann werden die Nachrichten spec-konform in wahlloser Reihenfolge erneut gesendet und es kommt zur Disko.
Das sollte sich in den Griff kriegen lassen, wenn die Nachrichten kontrolliert erst dann gesendet werden, wenn die vorherige empfangen wurde. Im Regelfall dauert das nur ein paar wenige ms.
Kannst du auch ein tcpdump erstellen, um auszuschließen, dass da noch was passiert, was wir nicht sehen?
-
Wenn der Dump läuft, scheint es zu gehen. Der Fehler ist dabei nicht aufgetreten. Wird durch den Dump eine Verzögerung bewirkt?
Gruß,
Moses123
-
Hallo AlCalzone,
sorry ich hatte noch eine weitere Baustelle.
Ich hatte schon gedacht ich bekomme den Adapter diesmal gar nicht abgeschossen.
Nachher hat es doch geklappt.
Log per PN.
Gruß
Rainer
