Programme werden nicht mehr ausgeführt

[homie_]

leider der gleiche Fehler - denke nun das hängt mit dem Einsatz des Rapi 3+ zusammen. Muss erst mal wieder den 3er Raspi starten bis es was neues zu dem Thema gibt

[srunschke]

Halte ich für unwahrscheinlich - ich habe nämlich keinen Pi3+, sondern nur den normalen 3er und das Problem trotzdem.

[Hypnos]

Hallo,

ich hab auch das Problem, das nach ca. 2bis 3 Tagen plötzlich keine Aktionen in Programmen ausgeführt werden (Dann-Zweige).

Das Problem tritt auf seit dem Wechsel von 2.31.25.20180225 auf 2.31.25.20180428.

Mit 2.31.25.20180225 und vorher gab es das Problem nie. Die einzige Änderung an den Programmen mit 2.31.25.20180428 war, das ich die DutyCycle Programme die ich bereits hatte gelöscht habe.

Die WebUI lässt sich normal aufrufen. Unter Status & Bedienung > Programme werden bei den nicht ausgeführten Programmen der Zeitstempel für Letzte Ausführung aktualisiert, die Aktionen (Licht anschalten/ Rolläden öffnen) wird nicht ausgeführt.

Im Log gibt es keine Auffälligkeiten.

Ein Neustart behebt das Problem, bis es nach ca. 2-3 Tagen wieder auftritt.
Als Hardware benutze ich ein RaspberryPi 2B.

Eine Besonderheit noch: Die Version 2.31.25.20180428 konnte ich nicht mit Etcher auf die SD Karte spielen, sondern nur mit Win32Diskimager. Hier bringt Etcher einen Fehler "access denided". (Ich hab auch mehrere SD Karten probiert, damit liegt es nicht daran.) Laut Etcher Forum haben andere dieses Problem auch. Hier wurde geäußert, das es an der aktuellen Windows 10 Version liegt.

[Hypnos]

Noch als Hinweis:
Im Einsatz habe ich:
- Homematic Wired Komponenten (viele)
- Homematic RF Komponenten (wenige)
- Homematic IP Komponenten (wenige)
Ich habe kein Phillips HUE oder Osram Lightify im Einsatz.

Das Problem liegt nicht an DutyCycle (da Homematic Wired Komponenten betroffen sind).

Ich aktualisiere gerade auf 2.31.25.20180526 und werde mal sehen ob es damit immer noch auftritt.

[homie_]

Hi,
willkommen im Club!
Mich wundert es, dass jetzt erst der dritte im Bunde dieses Problem hat. Bin auch wieder zurück auf 2.31.25.20180120 gegangen. Da läuft wieder alles wie gewohnt. Falls jemand das gleiche Problem hat, bitte hier reinschreiben .

Danke

[jmaus]

Ihr (die betroffen sind) müssten wirklich mal versuchen das genauer zu debuggen. D.h. Den loglevel hochnehmen, debug ausgaben in programme reinmachen, programme deaktivieren und dann schritt fpr schritt einschalten, etc. Und dann mal versuchen untereinander zu versuchen Gemeinsamkeiten zu finden denn anscheinend ist das Problem nur auf euch limitiert und wenn es ein generelles Problem geben sollte müsste der Aufachrwi wesentlich größer sein. Ich kann das hier leider auch nicht reproduzieren.

[Hypnos]

Hallo,

mit 2.31.25.20180526 ist mir das Problem gerade wieder aufgefallen. Wann es genau aufgetreten ist weiß ich nicht. Man spürt nur die Auswirkungen (Rolläden fahren nicht, Programme, welche RF Komponenten auf Wired umsetzen gehen nicht, etc...).

Es gibt die rega 2 mal:

Code: Alles auswählen

# ps|grep rega
  825 root      18:40 /bin/ReGaHss -f /etc/rega.conf -l 2
20632 root       0:00 /bin/ReGaHss -f /etc/rega.conf -l 2
30360 root       0:00 grep rega

Ich hab mal ein Testprogramm erstellt:

Dann hab ich das Programm unter "Status & Bedienung" --> "Programme" ausgeführt. Erwartet hätte ich das der erste Zweig ausgeführt wird - also das Licht schaltet an. Ergebnis, Zeitstempel letzte Ausführung ändert sich, Licht wird nicht eingeschaltet.

Danach hab ich den Button mal kurz, mal lang betätigt. Ergebnis, Zeitstempel letzte Ausführung ändert sich, Licht wird nicht eingeschaltet.

Screenshot 2018-05-28 at 22.48.12.png (23.15 KiB) 1750 mal betrachtet

Ich hab das Programm auch nochmal etwas angepasst:

Das brachte aber keine Besserung.

Die Logikschicht hatte ich währenddessen auf alles loggen gesetzt und den Ausschnitt hier:

Code: Alles auswählen

May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="CUX9002218:1"."DEW_POINT"=5.150000 [iseXmlRpc.cpp:321]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 3940 [iseDOMchn.h:288]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 3925 [iseDOMdev.h:225]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [iseRTEvent.cpp:69]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 5.150000 [iseXmlRpc.cpp:364]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="CUX9002218:1"."ABS_HUMIDITY"=6.320000 [iseXmlRpc.cpp:321]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 3940 [iseDOMchn.h:288]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 3925 [iseDOMdev.h:225]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [iseRTEvent.cpp:69]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 6.320000 [iseXmlRpc.cpp:364]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [httpListener.cpp:208]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: recvd 907 bytes by web server #1 [httpServer.cpp:764]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@7Zz0f12g3R@ [iseSession.cpp:185]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: IseXmlRpc::GetInterfaceByID: interface name= BidCos-Wired for ID=1238 [iseXmlRpc.cpp:2549]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="BidCoS-Wir:1"."PRESS_SHORT"=true [iseXmlRpc.cpp:321]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: IseXmlRpc::CallXmlrpcMethod: method =setValue params = {"BidCoS-Wir:1","PRESS_SHORT",true} result = "" [iseXmlRpc.cpp:2610]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1241 [iseDOMchn.h:288]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1239 [iseDOMdev.h:225]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 2478 'AA_TestProg' with valNew = '1' ; oidTrigger = 1243 [iseDOMPrg.cpp:478]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_CHANGE is set, trigger dp exists  [iseCondition.cpp:524]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [iseCondition.cpp:388]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, CONDITION_TRIGGER_EXECUTE is reset. [iseCondition.cpp:1112]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [iseCondition.cpp:1119]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [iseCondition.cpp:484]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [iseCondition.cpp:388]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 1 [iseCondition.cpp:1119]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, CONDITION_TRIGGER_EXECUTE is reset. [iseRule.cpp:227]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [iseRule.cpp:235]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 2478 'AA_TestProg'; Destination ID = 2483 [iseDOMPrg.cpp:501]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: IseDOMPrg::SetState: break execute for Program ID = 2478 'AA_TestProg', rule's trigger flags = 0 [iseDOMPrg.cpp:521]
May 28 23:01:02 homematic-ccu local0.info ReGaHss: Info: http id #1 sends parsed file [httpServer.cpp:2022]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [iseRTEvent.cpp:69]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1241 [iseDOMchn.h:288]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1239 [iseDOMdev.h:225]
May 28 23:01:02 homematic-ccu local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [iseXmlRpc.cpp:364]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [httpListener.cpp:208]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: recvd 961 bytes by web server #1 [httpServer.cpp:764]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@7Zz0f12g3R@&action=UpdateUI [iseSession.cpp:185]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: http id #1 sends parsed file [httpServer.cpp:2022]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [httpListener.cpp:208]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: recvd 918 bytes by web server #1 [httpServer.cpp:764]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/datapointconfigurator.htm?sid=@7Zz0f12g3R@ [iseSession.cpp:185]
May 28 23:01:04 homematic-ccu local0.info ReGaHss: Info: http id #1 sends parsed file [httpServer.cpp:2022]

Leider sind das für mich Bömische Dörfer.

Danach hab ich einen Zentralen Neustart durchgeführt.
Jetzt arbeitet das Programm wie erwartet:
"Status & Bedienung" --> "Programme" schaltet das Licht ein.
Mit dem Taster kann man auch fröhlich ein und ausschalten.

[homie_]

ich muss mich mal damit befassen wie man Deine technischen Vorschläge einbaut (log lever erhöhen usw.)
Aufgefallen ist mir, dass wir alle 3 eine HUE Anbindung haben. VIelleicht kann man da mal anfangen.

[Hypnos]

Aufgefallen ist mir, dass wir alle 3 eine HUE Anbindung haben. VIelleicht kann man da mal anfangen.

Ich habe keine HUE Anbindung.

[homie_]

ups - stimmt. Hatte ich falsch in Erinnerung.