[GELÖST] Verzögerte Ausführung von Programmen

[Matthias K.]

Hallo zusammen,

seit einigen Tagen habe ich das Problem, dass meine RaspiMatic Programme des öfteren nur sehr verzögert ausführt. Es wirkt, als ob irgendwas die RM manchmal blockiert, evtl. ein Programm, das zu lange braucht, o.ä!?

Im Syslog habe ich regelmäßig alle 1-2 Minuten diese Fehlermeldungen:

Code: Alles auswählen

Feb 22 10:19:25 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","LEQ0099077:1","TEMPERATURE",-3.100000}],[methodName:"event",params:{"rcux","LEQ0099077:1","HUMIDITY",81}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:19:25 192.168.81.220 rfd: XmlRpc transport error
Feb 22 10:20:14 192.168.81.220 rfd: XmlRpc transport failed (first try), retrying...
Feb 22 10:20:24 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","NEQ0119269:1","BRIGHTNESS",121}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:20:24 192.168.81.220 rfd: XmlRpc transport error
Feb 22 10:20:34 192.168.81.220 rfd: XmlRpc transport failed (first try), retrying...
Feb 22 10:20:44 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","MEQ0269838:2","BOOT",true}],[methodName:"event",params:{"rcux","MEQ0269838:2","ENERGY_COUNTER",43067.600000}],[methodName:"event",params:{"rcux","MEQ0269838:2","POWER",0.140000}],[methodName:"event",params:{"rcux","MEQ0269838:2","CURRENT",32.000000}],[methodName:"event",params:{"rcux","MEQ0269838:2","VOLTAGE",232.500000}],[methodName:"event",params:{"rcux","MEQ0269838:2","FREQUENCY",49.990000}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:20:44 192.168.81.220 rfd: XmlRpc transport error
Feb 22 10:22:11 192.168.81.220 rfd: XmlRpc transport failed (first try), retrying...
Feb 22 10:22:21 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","MEQ0273230:2","ENERGY_COUNTER",5828.600000}],[methodName:"event",params:{"rcux","MEQ0273230:2","POWER",0.020000}],[methodName:"event",params:{"rcux","MEQ0273230:2","CURRENT",0.000000}],[methodName:"event",params:{"rcux","MEQ0273230:2","VOLTAGE",232.600000}],[methodName:"event",params:{"rcux","MEQ0273230:2","FREQUENCY",50.000000}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:22:21 192.168.81.220 rfd: XmlRpc transport error
Feb 22 10:22:31 192.168.81.220 rfd: XmlRpc transport failed (first try), retrying...
Feb 22 10:22:41 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","MEQ0269838:2","BOOT",true}],[methodName:"event",params:{"rcux","MEQ0269838:2","ENERGY_COUNTER",43067.600000}],[methodName:"event",params:{"rcux","MEQ0269838:2","POWER",0.140000}],[methodName:"event",params:{"rcux","MEQ0269838:2","CURRENT",32.000000}],[methodName:"event",params:{"rcux","MEQ0269838:2","VOLTAGE",232.500000}],[methodName:"event",params:{"rcux","MEQ0269838:2","FREQUENCY",50.000000}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:22:41 192.168.81.220 rfd: XmlRpc transport error
Feb 22 10:22:51 192.168.81.220 rfd: XmlRpc transport failed (first try), retrying...
Feb 22 10:23:01 192.168.81.220 rfd: XmlRpcClient error calling event({[methodName:"event",params:{"rcux","MEQ1599449:1","TEMPERATURE",22.500000}],[methodName:"event",params:{"rcux","MEQ1599449:2","TEMPERATURE",18.500000}],[methodName:"event",params:{"rcux","MEQ1599449:3","TEMPERATURE",4.000000}],[methodName:"event",params:{"rcux","MEQ1599449:4","TEMPERATURE",-4.000000}],[methodName:"event",params:{"rcux","MEQ1599449:0","LOWBAT",false}],[methodName:"event",params:{"rcux","MEQ1599449:1","LOWBAT",false}],[methodName:"event",params:{"rcux","MEQ1599449:2","LOWBAT",false}],[methodName:"event",params:{"rcux","MEQ1599449:3","LOWBAT",false}],[methodName:"event",params:{"rcux","MEQ1599449:4","LOWBAT",false}],[methodName:"event",params:{"rcux","MEQ1599449:5","LOWBAT",false}]}) on binary://127.0.0.1:8701/RPC2:
Feb 22 10:23:01 192.168.81.220 rfd: XmlRpc transport error

Die Seriennummern in den Meldungen gehören zu folgenden Geräten:
LEQ0099077: HM-WDS10-TH-O (Außentemperatur/Feuchtesensor)
MEQ0269838: HM-ES-PMSw1-Pl (Leistungsmess-Zwischenstecker)
MEQ0273230: HM-ES-PMSw1-Pl (Leistungsmess-Zwischenstecker)
MEQ1599449: HM-WDS30-OT2-SM (Differenztemperatursensor)
NEQ0119269: HM-Sen-MDIR-O (Bewegungsmelder)

Was sagen diese Meldungen?
Bzw. wie finde ich die Ursache?

System: RM 2.31.25.20180120 auf Raspi3
AddOns: CuXD 1.12, XML-API 1.15, E-Mail 1.6.8, Programme Drucken 1.2a, check_mk_agent 1.2
Externe Zugriffe auf meine RM: 2 Tablets mit Tinymatic, sonst im Moment nur Anwesenheitsscript auf separatem Raspi, das eine Sysvar befüllt. Keine Portweiterleitungen, Zugriff von Außen nur spradisch über gesicherten ReverseProxy mit HTTPS und Authentifizierung.

[lesiflo]

Hallo,
da fällt mir spotan Duty Cycle ein. Hast du den schon mal ermittelt, bzw. mal hier danach gesucht? Bei vielen solchen Fehlern ist oft ein erhöhter Duty Cycle das Problem. Bei mir übrigens auch schon mal.

[alchy]

Dann lies mal >> H I E R << oder auch >> H I E R <<
die Erklärungen von Familienvater. Man muss ja nicht alles doppelt und dreifach posten.


Alchy

[Matthias K.]

da fällt mir spotan Duty Cycle ein. Hast du den schon mal ermittelt, bzw. mal hier danach gesucht?

Ja, habe ich. Bin trotz recht umfangreicher Installation eigentlich immer unter 20%, meist unter 10.

Dann lies doch mal >> H I E R << oder auch >> H I E R <<
die Erklärungen von Familienvater. Man muss ja nicht alles doppelt und dreifach posten.

Danke, das hatte ich auch schon gefunden, aber nicht mit meinem Problem in Zusammenhang gebracht, da ich kein HPCL nutze (und auch keine andern Sachen wie IOBroker, die die Logik von der CCU runternehmen).
Daher ist mir jetzt auch nicht ganz klar, wie das bei meinem Problem hilft!?

Klar, für die Energiemessschaltdinger wird ja jeweils auch ein internes Script für den Leistungszähler erstellt, der auf Aktualisierung triggert.
Auch die anderen von mir genannten Devices sind jeweils in einem Programm drin, das auf Aktualisierung triggert.
Das sind aber (außer insgesamt 5 internen Energiemessdingsda-Programmen) insgesamt nur 3 Programme, die jeweils ein kleines Script starten.
Beim Differenztemperatursensor zum Glätten des Wertes (Sonnensensor), Beim Bewegungsmelder zum Aktivieren des Tablets per Home24-Mediaplayer und Tasker und beim Außentemperatursensor zum Befüllen der Außentemperatur in meiner Poolsteuerung (HTTP-Request). Die Scripte laufen aber (im Script-Testen-fenster getestet) alle nur ~1s bzw. nicht messbar lange und ohne Fehler im Log (trotz Community-Rega, das hab ich alles schon in Test mit früheren Rega-Betas bereinigt)...

Wo kann ich noch ansetzen? Bzw. wie finde ich raus, warum die Exec Engine abschmiert (das hab ich doch richtig verstanden, dass das die Meldungen besagen?)?

[lesiflo]

Hast in den Programmen triggern auf Aktualisierung drin? Wenn ja die mal auf bei Änderung ändern. Aktoren nie gleichzeitig schalten sondern mit 1 Sekunde Verzögerung nacheinander. Da würde ich mal ansetzen.

Gesendet von meinem FRD-L09 mit Tapatalk

[Familienvater]

Hi,

Bzw. wie finde ich raus, warum die Exec Engine abschmiert (das hab ich doch richtig verstanden, dass das die Meldungen besagen?)?

Wenn Du kein HPCL nutzt, dann hast Du auch keine ExecEngine...

Da in Deinen Meldungen immer von rcux die Rede ist, würde ich wegen der Übereinstimmung bei 3 Buchstaben einfach mal auf cuxd tippen, das der als Abonnent die Meldungen nicht mehr quittiert.

Der Familienvater

[Matthias K.]

Hallo Familienvater,

rcux ist mir auch aufgefallen, dann werd ich wohl mal im CuXD-Forenteil nachfragen.
Was mir schleierhaft ist: Bis vor ca. 3 Tagen hat alles problemlos funktioniert.

Ich habe die letzten Monate (fast) alles, was bisher mit dem CuXD-Exec erledigt wurde, wieder zurückgestellt auf system.Exec, da der ja inzwischen stabil läuft.
CuXD nutze ich nur noch zur Anbindung von ein paar Fremd-Temperatursensoren und als virtuelle Devices für meine Poolsteuerung (bei Tastendruck wird jeweils ein HTTP-Request an die Pool-API gesendet). Pool ist aber jetzt eh aus und damit auch dieser CuXD-Teil nicht in Verwendung...

Hast in den Programmen triggern auf Aktualisierung drin? Wenn ja die mal auf bei Änderung ändern. Aktoren nie gleichzeitig schalten sondern mit 1 Sekunde Verzögerung nacheinander. Da würde ich mal ansetzen.

Wie ich schrieb: ein paar wenige Programme triggern (gezwungenermaßen) auf Aktualisierung, aber der Großteil nicht.
Ich achte schon sehr auf Funkhygiene, sprich: verzögertes Schalten, Prüfen vorm Schalten, ob evtl. schon an, etc. Bin inzwischen doch schon einige Jahre dabei (ca. seit 2013), nur nicht so ein aktiver Schreiber.
Ich habe damals, wie es sich als Anfänger gehört, erst mal viel gelesen (Tipps für Anfänger, Forenbeiträge, etc.) und mir mein Wissen erarbeitet.

Ich will jetzt nicht sagen dass ich Profi in HM bin, aber doch eher fortgeschrittener Amateur...

[lesiflo]

Hast du schon mal alle Programme,in denen die mit Fehler gemeldeten Geräte drin sind, deaktiviert und dann nacheinander wieder aktiviert? So könntest du vielleicht erstmal herausbekommen welches der Geräte die Ursache ist. Nur so eine Idee.

[uwe111]

Was sagen diese Meldungen?
Bzw. wie finde ich die Ursache?

Also anhand Deiner Meldungen läuft m.E. der CUxD nicht. Starte ihn mal neu.

Viele Grüße

Uwe

[Matthias K.]

Hast du schon mal alle Programme,in denen die mit Fehler gemeldeten Geräte drin sind, deaktiviert und dann nacheinander wieder aktiviert?

Ja, habe ich. Aber noch nicht gemacht, weil so viele...
Ich hatte die Hoffnung, dass ich den Übeltäter so finde.

Also anhand Deiner Meldungen läuft m.E. der CUxD nicht. Starte ihn mal neu.

Hab ich gemacht, ändert aber nichts an den Meldungen und am Verhalten.
Hab auch schon mehrfach den Raspi neugestartet, CuXD drüberinstalliert und auch schon eine neue SD-Karte mit RaspiMatic geflasht und Backup rückgespielt.

Fehlanzeige...

edit:
Uwe, ich habe vor Kurzem 2 CuXD-Geräte (Exec und Timer) gelöscht, die ich nirgendwo mehr genutzt habe. Könnte es sein, dass da irgendwo die Ursache ist?
Wobei ein Rückspielen eines Backups von vor ca. 1 Woche und anschließendem Wiederholen der seitdem durchgeführten Änderungen auch nichts geändert hat.