ioBroker legt WebUI lahm

[debianatoe]

Danke für die Hinweise! Ich habe jetzt die Protokolle aller Instanzen von BINRPC auf XMLRPC umgestellt. Mal sehen, ob sich dadurch etwas verbessert. Mein ioBroker läuft auf einer realen x86-Maschine. Im Cux-Log sehe ich sehr viele INIT-Meldungen der Instanz RPC0:

Code: Alles auswählen

Dec  6 19:11:47 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:12:19 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:12:19 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:12:50 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:12:50 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:13:21 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:13:21 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:13:52 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:13:52 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:14:23 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:14:23 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:14:54 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:14:54 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:15:25 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:15:25 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:15:57 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:15:57 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:16:28 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:16:28 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:16:59 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:16:59 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:17:30 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:17:30 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''
Dec  6 19:18:01 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' 'hm-rpc.0'
Dec  6 19:18:01 homematic-ccu2 daemon.info cuxd[271]: INIT 'xmlrpc_bin://192.168.3.11:18701' ''

Ist das normal? Dabei ist die CUX-Instanz (RPC0) meist grün, während die WIRELESS-Instanz (RPC1) häufiger mal auf gelb wechselt. Im Fullsyslog finde ich solche Einträge:

Code: Alles auswählen

Dec  7 11:35:24 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec  7 11:36:27 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec  7 11:36:47 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","BOOT",true}],[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","ENERGY_COUNTER",19458.300000}],[methodName:"event",params:{"hm-rpc.1","OE
Dec  7 11:36:47 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec  7 11:37:07 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","CONTROL_MODE",1}],[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","FAULT_REPORTING",0}],[methodName:"event",params:{"hm-rpc.1","NEQ1640
Dec  7 11:37:07 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec  7 11:37:31 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:

Über die Firmware 2.41.5 liest man wenig bis keine Klagen. Ich werde das mal zum Anlaß nehmen und ein Update testen. Viel Verbesserung verspreche ich mir hier allerdings nicht. Denn meine HMIP-Instanz (RPC2) ist eigentlich immer grün und scheint eher nicht das Problem zu sein.

[Familienvater]

Hi,

Cuxd kann (AFAIK noch) kein PingPong, deswegen loggt er die Pings vom ioBroker als Fehler. Wobei ich ein 30 Sekunden-Ping-Intervall schon fast als "Paranoid" bezeichne, 180 Sekunden ist da für mich absolut ausreichend, im Falle eines Neustarts braucht die Zentrale eh Zeit zum Booten und zum "Aufrappeln".

Ich weiß jetzt nicht, wie viel CPU-Bums und Speicher Deine x86-Maschine hat, wie die netzwerktechnisch angebunden ist (WLAN, PowerLAN, "richtiges" Netzwerkkabel), und was die sonst noch so treibt, aber ich habe eben mal das komplette Syslog meiner CCU seit 0 Uhr heut morgen durchgegrept, da habe ich nicht einen error in Verbindung mit hm-rpc.

Der Familienvater

[debianatoe]

dtp hat das Pollingintervall auf 5 Sekunden. Ich hatte es auf den von ioBroker voreingestellten 30 Sekunden belassen. Wenn das System mit 180 Sekunden stabiler werden sollte, wäre ich zufrieden. Ich werde das testen. In dem Rega-Interface gibt es ja 2 Pollingzeiten, die man verändern kann. Bei mir stehen beide auf 30 Sekunden. Welche sollte man denn auf 180 Sekunden stellen?

Mein x86-Rechner ist alt und leistungsschwach, aber natürlich gegenüber eine CCU2 immer noch ein Powerrechner: 1 CPU (1,4GHz / 2 Befehlseinheiten). Die Rechner sind über ein "richtiges" LAN-Kabel angebunden. Ich kann mir nicht vorstellen, daß die Error-Meldungen durch eine Überlastung des x86-Rechners verursacht werden. LOAD, CPU-Auslastung und RAM liegen immer im grünen Bereich. Wahrscheinlicher scheint mir ein grundsätzliches (Konfigurations-)Problem. Denn die WebUI friert hin und wieder immer noch ein oder reagiert mit Verzögerungen im Minutenbereich. Beim Zugriff über den CuxD oder ssh habe ich zeitgleich keine Probleme.

[debianatoe]

Ich habe jetzt die beiden Pollingzeiten auf 180 Sekunden angehoben.
Dann habe ich noch die XML-API auf Version 1.20 und den CUx-Daemon auf Version 2.2.0 angehoben.

Das hat leider alles keine Verbesserung gebracht. Nun steht noch das Firmware-Update der CCU2 an ...

[debianatoe]

Ich habe jetzt die beiden Pollingzeiten auf 180 Sekunden angehoben.

Die eine heißt "Wiederverbindungsintervall (s) hm-rpc Instanz" und die andere "Intervalle (s)".

[debianatoe]

Ich habe jetzt die Firmware der CCU2 auf Version 2.41.5 upgedated. Somit sind alle Geräte und AddOns auf dem aktuellen Stand. Leider besteht das Problem nach wie vor: sobald ich den ioBroker-Dienst aktiviere, friert die WebUI der CCU2 phasenweise ein und wird unbenutzbar. Hier ein aktueller Auszug aus dem Error-Log der CCU2:

Code: Alles auswählen

Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"CUX2801001:15","CMD_RETL"} result= [faultCode:-4,faultString:"Unknown parameter"] [iseXmlRpc.cpp:2605]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =  [iseDOMdpHSS.cpp:130]
Dec 13 11:14:33 homematic-ccu2 daemon.warn cuxd[565]: use CUX2801001:16.CMD_QUERY_RET=1 to activate CUX2801001:16.CMD_RETS command!
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"CUX2801001:16","CMD_RETS"} result= [faultCode:-4,faultString:"Unknown parameter"] [iseXmlRpc.cpp:2605]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =  [iseDOMdpHSS.cpp:130]
Dec 13 11:14:33 homematic-ccu2 daemon.warn cuxd[565]: use CUX2801001:16.CMD_QUERY_RET=1 to activate CUX2801001:16.CMD_RETL command!
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"CUX2801001:16","CMD_RETL"} result= [faultCode:-4,faultString:"Unknown parameter"] [iseXmlRpc.cpp:2605]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Dec 13 11:14:33 homematic-ccu2 local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =  [iseDOMdpHSS.cpp:130]
Dec 13 11:14:39 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","BOOST_STATE",0}],[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","ACTUAL_TEMPERATURE",13.200000}],[methodName:"event",params:{"hm-rpc.1
Dec 13 11:14:39 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:16:06 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec 13 11:16:26 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","BOOT",true}],[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","ENERGY_COUNTER",3827.900000}],[methodName:"event",params:{"hm-rpc.1","OEQ
Dec 13 11:16:26 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:16:46 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","VALVE_STATE",0}],[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4","BOOST_STATE",0}],[methodName:"event",params:{"hm-rpc.1","NEQ1640XXX:4
Dec 13 11:16:46 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:16:57 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL:0 [iseXmlRpc.cpp:2167]
Dec 13 11:17:09 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec 13 11:18:12 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec 13 11:18:32 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","BOOT",true}],[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","ENERGY_COUNTER",3834.800000}],[methodName:"event",params:{"hm-rpc.1","OEQ
Dec 13 11:18:32 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:18:53 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","NEQ0774XXX:1","TEMPERATURE",16.100000}],[methodName:"event",params:{"hm-rpc.1","NEQ0774XXX:2","TEMPERATURE",16.600000}],[methodName:"event",params:{"hm-rpc.
Dec 13 11:18:53 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:19:16 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec 13 11:20:19 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"hm-rpc.1"}) on http://192.168.X.X:12001/RPC2:
Dec 13 11:20:27 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL:0 [iseXmlRpc.cpp:2167]
Dec 13 11:20:39 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","BOOT",true}],[methodName:"event",params:{"hm-rpc.1","OEQ0571XXX:2","ENERGY_COUNTER",3840.200000}],[methodName:"event",params:{"hm-rpc.1","OEQ
Dec 13 11:20:39 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 13 11:20:59 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"hm-rpc.1","OEQ0801XXX:1","TEMPERATURE",17.900000}],[methodName:"event",params:{"hm-rpc.1","OEQ0801XXX:2","TEMPERATURE",18.100000}],[methodName:"event",params:{"hm-rpc.
Dec 13 11:20:59 homematic-ccu2 user.err rfd: XmlRpc transport error

Hat jemand noch eine Idee? Die ioBroker-Instanzen hm-rega.0 sowie hm-rpc.0 bis 2 sind trotz der Error-Meldungen alle grün. Im Logfile des ioBrokers gibt es keine Error-Meldungen. Aber so ist der ioBroker für mich nicht einsetzbar.
Am ioBroker fällt mir auf, daß sich die Werte der Objekte nur sehr selten (eher Stundentakt) verändern, obwohl die CCU2 ja normalerweise alle paar Minuten neue Werte bekommt.

[Familienvater]

Hi,

wenn die CCU Fehlermeldungen im Log hat, das es Probleme beim Melden von Events an ioBroker gibt, dann ist das Problem nach meinem Verständnis im Netzwerk oder auf dem ioBroker-Rechner zu suchen. Ich habe bei mir keinerlei Fehlermeldungen, das die CCU Probleme hat, Events bei ioBroker abzuliefern. Und ich weiß, was bei mir los ist, ich habe im Mittel fast 5 Events pro Sekunde, und die werden nicht nur an ioBroker gesendet, sondern auch noch an einen weiteren externen XMLRPC-Logger, und lokal auf der Zentrale läuft auch noch HPCL, was die Events auch alle gepusht bekommt. Ich habe nur bei mir keine cuxd-Instanz mehr, keine Ahnung, ob Du "echte Geräte" per cuxd einbindest, oder cuxd auch nur für Timer/system-Exec Ersatz nimmst, dann könnte man sich das auch sparen, cuxd-Events an ioBroker zu melden.

Der Familienvater

[debianatoe]

Problem ist gelöst
Vielen Dank für Deine Hilfe!

Ursache war die Firewall auf dem x86-Rechner. Offenbar hatte das Warten der CCU2 auf die nicht zustellbaren Netzpakete die WebUI gelähmt.
Mich wundert nur, daß auf diese Art und Weise überhaupt Daten am ioBroker ankamen. Es kamen zwar nur sehr wenige ein, aber scheinbar wird die Datenübertragung mit unterschiedlichen Mechanismen durchgeführt.

Nun habe ich nur noch einen Fehler im Syslog:

Code: Alles auswählen

Dec 13 20:29:16 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL:0 [iseXmlRpc.cpp:2167]

Ich habe keine Ahnung, was der bedeutet.

[debianatoe]

Nun habe ich nur noch einen Fehler im Syslog:

Code: Alles auswählen

Dec 13 20:29:16 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL:0 [iseXmlRpc.cpp:2167]

Ich habe keine Ahnung, was der bedeutet.

Siehe viewtopic.php?f=26&t=45596&start=20

[FritzRe]

Ursache war die Firewall auf dem x86-Rechner. Offenbar hatte das Warten der CCU2 auf die nicht zustellbaren Netzpakete die WebUI gelähmt.

Hallo

Was hast du an der Firewall umgestellt, daß das Problem gelöst hat?