Magic Licht

[shuo]

Hallo zusammen,

ich habe ein sehr seltsames Problem.
Neuerdings schaltet sich bei mir im Wohnzimmer das Licht wie aus Geisterhand ein. Es hatte vor einiger Zeit um kurz vor 20 Uhr angefangen und jetzt bin ich bei kurz vor 21 Uhr angekommen. Ich kann täglich einen Versatz von ein paar Minuten feststellen.
Ich habe keinerlei Rule die irgendwas ein oder ausschaltet, daher ist das ganze sehr mysteriös.
Zum System:
- Openhab 3.3.0.M6
- Licht wird über Homematic Binding gesteuert

Wenn ich das Item für das Licht auskommentiere, habe ich das Problem nicht mehr.

Hat jemand eine Idee, bzw. einen Vorschlag wie ich weiter vorgehen kann um die Ursache zu finden?

Viele Grüße und Danke!

[udo1toni]

Was steht denn zur Geisterstunde im Log?

[shuo]

Hi Udo.
Das ist ja das seltsame. Ich konnte nichts im log finden. Ich hab zwar einige Warnings / Errors, aber nichts, dass auf ein Einschalten des Lichts hindeutet.

[shuo]

Vielleicht doch etwas....In events habe ich 2 Minuten vorher diesen Eintrag gefunden. Allerdings habe ich kein solches ITEM:

Code: Alles auswählen

2022-06-16 21:29:00.003 [INFO ] [openhab.event.ChannelTriggeredEvent ] - astro:sun:65cae8125d:daylight#event triggered END

[udo1toni]

Das ist kein Item, das ist ein Channel.

Es muss aber im Log ein received command ON vorkommen, sonst kann openHAB gar keinen Befehl an den passenden Channel senden.

"Licht geht immer später an" passt aber gut zum Astro Binding, wird sich dann ab dem 21 wieder nach vorne verschieben

[shuo]

Das müsste doch im event-log auftauchen, oder?
Ich kann heute Abend nochmal nachschauen. Bin echt ratlos, sobald ich openhab runterfahre bleibt es dunkel...

[udo1toni]

Genau, das taucht im events.log auf (der Channel ebenso...).

[shuo]

Es ist da. Entweder war ich blind oder ich weiß auch nicht.

Code: Alles auswählen

2022-06-17 21:32:23.445 [INFO ] [openhab.event.ItemCommandEvent      ] - Item 'Wohnzimmer_Light' received command ON

Wie dem auch sei, ich habe keinerlei Rule oder irgendwas dergleichen:

Wohnzimmer_Licht_Visual.png

Habe ich hier irgendwo Geister im Haus???

[udo1toni]

Na ja, den Screenshot hättest Du Dir getrost sparen können...
Fest steht ja, dass irgendwo der Befehl zum Einschalten her kommt. Was passiert unmittelbar vorher im Log?

Tipp: Die Logs lassen sich wunderbar über die Konsole abfragen, z.B. so:

Code: Alles auswählen

cat /var/log/openhab/events.log | grep "'Wohnzimmer_Light' received command ON" -B5

Diese Anweisung zeigt jeweils nicht nur die Zeile mit dem ON-Command, sondern auch die fünf Zeilen davor.
Was sonst noch so im System passiert ist, müsste man über den Zeitstempel abgleichen, den man aus der ersten Antwort sehen kann.

[shuo]

Hi Uwe, das was vorher passiert ist völlig unspektakulär:

Code: Alles auswählen

openhabian@openhabian:~ $ cat /var/log/openhab/events.log | grep "'Wohnzimmer_Light' received command ON" -B15
2022-06-18 21:32:42.541 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'PowerApparent_S_Phase_3' changed from 265.46939999999995 to 262.17100000000005
2022-06-18 21:32:42.544 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Voltage_AC_PhaseToPhase_12' changed from 406.8 to 406.2
2022-06-18 21:32:42.547 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'PowerApparent_S_Phase_1' changed from 366.5209 to 366.4452
2022-06-18 21:32:42.549 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'PowerApparent_S_Phase_2' changed from 344.86199999999997 to 340.81759999999997
2022-06-18 21:32:42.550 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'PowerReactive_Q_Phase_2' changed from -115.90000000000001 to -115.94
2022-06-18 21:32:42.552 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'PowerReal_P_Phase_3' changed from 244.06 to 241.44
2022-06-18 21:32:44.220 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sensor_CPUTemp' changed from 51.5 to 52.1
2022-06-18 21:32:44.541 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Symo_Inverterdatachanneludc' changed from 344.4 to 0
2022-06-18 21:32:44.543 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Symo_ErrorCode' changed from 306 to 0
2022-06-18 21:32:44.545 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Symo_StatusCode' changed from 3 to 2
2022-06-18 21:32:44.547 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Symo_Powerflowchannelpgrid' changed from 817.98 to 820.46
2022-06-18 21:32:44.548 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Symo_Powerflowchannelpload' changed from -817.98 to -820.46
2022-06-18 21:32:45.381 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sensor_CPUTemp' changed from 52.1 to 51.5
2022-06-18 21:32:45.557 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'homematic:HM-SCI-3-FM:3014F711A0001F5A4993D05C:LEQ0563861' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE
2022-06-18 21:32:46.518 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sensor_CPUTemp' changed from 51.5 to 52.1
2022-06-18 21:32:51.374 [INFO ] [openhab.event.ItemCommandEvent      ] - Item 'Wohnzimmer_Light' received command ON