HomeMatic Socket (KeepAlive): Die Operation wird abgebrochen

Senior · 28. Dezember 2016 um 12:51

Ein möglicher Fehler liegt auch an der IP-Vergabe der Fritz.Box, hast du das Häkchen bei der Adresszuweisung (immer die gleiche Adresse verwenden) gemacht.

McHennsy · 28. Dezember 2016 um 13:58

Jupp, der Häkchen ist gesetzt.

Könnte irgendwas mit dem Port 5544 (Ereignisse) die Probleme verursachen.
Kann man den zu Testzwecken mal um konfigurieren?

Nall-chan · 28. Dezember 2016 um 14:06

Ja kannst fast einen beliebigen nehmen, aber nicht vergessen die Firewall anzupassen auf dem System wo IPS läuft.
Michael

McHennsy · 28. Dezember 2016 um 16:12

Kannst Du mir vielleicht noch sagen wie ich der CCU sagen kann, dass diese jetzt auf einem anderen Port senden soll?

Nall-chan · 28. Dezember 2016 um 16:17

Das macht IPS…
Michael

McHennsy · 28. Dezember 2016 um 16:20

Danke Michael, habe dazu gerade auch diesen Foreneintrag (von Dir) gefunden.
Ereigniss Port CCU

McHennsy · 28. Dezember 2016 um 16:55

War wieder etwas auf Fehlersuche und habe folgendes ausprobiert:

[ol]
[li]Homematic Socket: Port 5544 auf 5545 geändert --> gleicher Fehler
[/li][li]Homematic im abgesicherten Modus gestartet --> gleicher Fehler
[/li][li]CCU2 Firewall angepasst (siehe Screenshot) --> gleicher Fehler
[/li][/ol]

Nall-chan · 28. Dezember 2016 um 17:11

Unter welchen OS läuft IPS ?
Die Firewall dort muss den Port per TCP ankommend/eingehend erlauben.
Wäre die Firewall auf der CCU falsch, würde gar nix gehen…
Michael

r4m3u5 · 28. Dezember 2016 um 17:12

So als Tipp zur CCU-Firewall: wenn Du das 192.168.0.0/16 freigibst, kannst Du Dir alles weitere was mit 192.168. anfängt sparen
Der 10er-Bereich mit der /12 ist wahrscheinlich auch sinnlos. Oder hast Du Netze/IPs im Bereich von 10.192.0.1 bis 10.207.255.254?

Evtl. mal ins Log der CCU geschaut, ob es da vielleicht einen Hinweis gibt?

Beste Grüße
/Jens

McHennsy · 28. Dezember 2016 um 17:12

Anbei auch noch mal ein Logfile der CCU. Ich frage mich, warum ich hier so viele Einträge „XmlRpc transport error“ oder „XmlRpcClient error calling event“ drin stehen habe?

Weiter unten ist auch zu sehen, dass ich den Port auf 5545 umgestellt hatte.


Dec 28 16:43:41 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:44:01 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","FEQ0057314:1","VALVE_STATE",0}],[methodName:"event",params:{"IPS","FEQ0057314:1","ERROR",0}]}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:44:01 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:49:27 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","LEQ0661714:2","BOOT",true}],[methodName:"event",params:{"IPS","LEQ0661714:2","ENERGY_COUNTER",495.700000}],[methodName:"event",params:{"IPS","LEQ0661714:2","POWE
Dec 28 16:49:27 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:49:52 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0965446:2","BOOT",true}],[methodName:"event",params:{"IPS","KEQ0965446:2","ENERGY_COUNTER",79106.700000}],[methodName:"event",params:{"IPS","KEQ0965446:2","PO
Dec 28 16:49:52 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:53:55 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"HMX_RF"}) on http://192.168.178.32:9010/RPC2:
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"HMX_RF"}) on http://192.168.178.32:9010/RPC2:
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","GEQ0008268:1","MOTION",false}],[methodName:"event",params:{"IPS","HEQ0405825:1","MOTION",false}],[methodName:"event",params:{"IPS","HEQ0508339:1","TEMPERATURE",2
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","HEQ0147262:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"IPS","HEQ0147262:2","ADJUSTING_DATA",34}],[methodName:"event",params:{"IPS","HEQ0514357:1","VA
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0966550:2","POWER",13.700000}],[methodName:"event",params:{"IPS","KEQ0966550:2","CURRENT",195.000000}],[methodName:"event",params:{"IPS","KEQ0966550:2","VOLTA
Dec 28 16:54:58 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:54:59 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","LEQ0197870:1","WORKING",true}],[methodName:"event",params:{"IPS","HEQ0508339:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"IPS","HEQ0508339:2","ADJUSTI
Dec 28 16:54:59 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:54:59 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"IPS"}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:54:59 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"IPS"}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:54:59 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","FEQ0064879:2","ADJUSTING_DATA",0}],[methodName:"event",params:{"IPS","FEQ0057314:1","VALVE_STATE",0}],[methodName:"event",params:{"IPS","FEQ0057314:1","ERROR",0}
Dec 28 16:55:00 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:00 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","CENTRAL","PONG","IPS"}]}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:55:00 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0966550:2","BOOT",true}]}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0966550:2","ENERGY_COUNTER",210181.200000}]}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0966550:2","POWER",1930.640000}]}) on http://192.168.178.42:5545/RPC2:
Dec 28 16:55:01 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:02 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"IPS","KEQ0966550:2","CURRENT",8775.000000}],[methodName:"event",params:{"IPS","KEQ0966550:2","VOLTAGE",220.100000}],[methodName:"event",params:{"IPS","KEQ0966550:2","F
Dec 28 16:55:02 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:18 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","GEQ0008268:1","MOTION",false}],[methodName:"event",params:{"HMX_RF","HEQ0405825:1","MOTION",false}],[methodName:"event",params:{"HMX_RF","HEQ0508339:1","TEMPE
Dec 28 16:55:18 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:38 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_DATA",34}],[methodName:"event",params:{"HMX_RF","HEQ05143
Dec 28 16:55:38 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:55:58 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","KEQ0966550:2","BOOT",true}],[methodName:"event",params:{"HMX_RF","KEQ0966550:2","ENERGY_COUNTER",210186.300000}],[methodName:"event",params:{"HMX_RF","KEQ0966
Dec 28 16:55:58 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:56:18 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_DATA",34}],[methodName:"event",params:{"HMX_RF","HEQ05143
Dec 28 16:56:18 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:56:38 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","HEQ0147285:1","TEMPERATURE",22.100000}],[methodName:"event",params:{"HMX_RF","HEQ0147285:1","HUMIDITY",62}],[methodName:"event",params:{"HMX_RF","HEQ0405825:1
Dec 28 16:56:38 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:56:58 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","GEQ0286244:2","PRESS_SHORT",true}],[methodName:"event",params:{"HMX_RF","GEQ0286244:2","INSTALL_TEST",true}],[methodName:"event",params:{"HMX_RF","BidCoS-RF:5
Dec 28 16:56:58 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:57:19 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","KEQ0966550:2","BOOT",true}],[methodName:"event",params:{"HMX_RF","KEQ0966550:2","ENERGY_COUNTER",210189.500000}],[methodName:"event",params:{"HMX_RF","KEQ0966
Dec 28 16:57:19 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:57:39 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","NEQ0774092:1","TEMPERATURE",0.400000}],[methodName:"event",params:{"HMX_RF","NEQ0774092:2","TEMPERATURE",10.700000}],[methodName:"event",params:{"HMX_RF","NEQ
Dec 28 16:57:39 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:57:59 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","GEQ0250047:1","TEMPERATURE",3.700000}],[methodName:"event",params:{"HMX_RF","GEQ0250047:1","HUMIDITY",76}],[methodName:"event",params:{"HMX_RF","IEQ0023171:1"
Dec 28 16:57:59 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:58:19 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","KEQ0966550:2","BOOT",true}],[methodName:"event",params:{"HMX_RF","KEQ0966550:2","ENERGY_COUNTER",210192.100000}],[methodName:"event",params:{"HMX_RF","KEQ0966
Dec 28 16:58:19 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 28 16:58:39 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","KEQ0194783:1","BRIGHTNESS",111}],[methodName:"event",params:{"HMX_RF","KEQ0966550:2","BOOT",true}],[methodName:"event",params:{"HMX_RF","KEQ0966550:2","ENERGY
Dec 28 16:58:39 homematic-ccu2 user.err rfd: XmlRpc transport error

Nall-chan · 28. Dezember 2016 um 17:17

Vermutlich weil die CCU dein IPS nicht erreicht…Firewall?
Michael

McHennsy · 28. Dezember 2016 um 17:20

PRETTY_NAME=„Raspbian GNU/Linux 8 (jessie)“
NAME=„Raspbian GNU/Linux“
VERSION_ID=„8“
VERSION=„8 (jessie)“
ID=raspbian
ID_LIKE=debian
Linux raspberryips 4.4.38-v7+ #938 SMP Thu Dec 15 15:22:21 GMT 2016 armv7l GNU/Linux

@Jens:
Ich glaube die 10er IP Range wird für den VPN Zugriff über „meine-homematic.de“ verwendet. Daher diese Freischaltung.

McHennsy · 28. Dezember 2016 um 17:22

Du meinst die Firewall auf dem IPS Raspberry? Da hatte ich noch gar nichts eingestellt. Vielleicht wurde diese mit einem Update aktiviert, was vorher nicht der Fall war? Kann so etwas sein?

r4m3u5 · 28. Dezember 2016 um 17:31

Ah! O.k. - wenn das so ist

Bin da bei Nall Chan - sieht aus, als würde Deine CCU sehr oft den IPS-Server nicht erreichen. Außer Firewall evtl. irgendwie bei den Netzwerkeinstellungen vertippt? Typo bei der Subnet-Mask o.ä?
Gibt es auf der CCU einen Prozess, der den Prozessor stresst (per ssh mal mit „top“ anschauen)?

Beste Grüße
/Jens

McHennsy · 28. Dezember 2016 um 17:45

Weder die CCU2 noch das IPS System haben viel zu tun.
Der IPS Raspberry idled mit 99% und die CCU2 mit 96%. Stress sieht für mich anders aus.

McHennsy · 29. Dezember 2016 um 07:47

Guten Morgen,

so wie es aussieht habe ich die Ursache meines KeepAlive problems finden und beheben können.

Mir haben die Fehlermeldungen…

Dec 29 05:33:22 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"HMX_RF"}) on [b]http://192.168.178.32[/b]:9010/RPC2:
Dec 29 05:34:25 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"HMX_RF"}) on [b]http://192.168.178.32[/b]:9010/RPC2:
Dec 29 05:34:45 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","IEQ0150666:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","IEQ0150666:2","ADJUSTING_DATA",0}],[methodName:"event",params:{"HMX_RF","IEQ015268
Dec 29 05:34:45 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 29 05:35:05 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","FEQ0064879:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","FEQ0064879:2","ADJUSTING_DATA",66}],[methodName:"event",params:{"HMX_RF","FEQ00573
Dec 29 05:35:05 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 29 05:35:25 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","HEQ0147262:1","TEMPERATURE",20.600000}],[methodName:"event",params:{"HMX_RF","HEQ0147262:1","HUMIDITY",58}],[methodName:"event",params:{"HMX_RF","IEQ0150666:2
Dec 29 05:35:25 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 29 05:35:46 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","HEQ0147262:2","ADJUSTING_DATA",0}],[methodName:"event",params:{"HMX_RF","HEQ051435
Dec 29 05:35:46 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 29 05:36:06 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"HMX_RF","CENTRAL","PONG","IPS"}],[methodName:"event",params:{"HMX_RF","JEQ0230841:2","ADJUSTING_COMMAND",0}],[methodName:"event",params:{"HMX_RF","JEQ0230841:2","ADJUS
Dec 29 05:36:06 homematic-ccu2 user.err rfd: XmlRpc transport error
Dec 29 05:37:40 homematic-ccu2 user.err rfd: Parameterset MASTER not found

…im Homematic Systemlog keine Ruhe gelassen, da im Logfile die IP 192.168.178.32, welche zu meinem MacBook gehört immer wieder im Zusammenhang mit den ganzen Fehlern aufgetaucht ist.
Konnte mir darauf einfach keinen Reim machen.

Heute Nacht ist mir dann aber etwas eingefallen, was diesen Fehler verursachen könnte.
Ich habe seit längerer Zeit eine Software am Mac (kSquare - HomeMenuX) laufen mit der ich verschiedene Zustände der Homematic Geräte in der Statusleiste anzeigen lassen kann. Temperaturen, etc.

Ich habe diese Software jetzt einfach mal wieder deinstalliert und gehofft, dass ich einen Schritt weiter komme.
Als erstes ist mir aufgefallen, dass die Fehlermeldungen oben seit dem nicht mehr aufgetaucht sind.
Ebenso ist der KeepAlive Fehler seitdem (jetzt schon über eine Stunde!) nicht mehr aufgetreten. Ich bin guter Hoffnung, dass ich die Ursache nun gefunden habe.
Wieso sich das aber solche Auswirkungen auf mein IP-Symcon System hat ist für mich immer noch unerklärlich.

Euer Hinweis, dass ich mir diese Meldungen im Logfile mal näher ansehen soll, hat mich auf die richtige Spur gebracht.
Ich habe diese Meldungen schon seit Ewigkeiten im Logfile. Vor IPS hatten diese halt einfach keinerlei Auswirkungen auf mein System. Da wäre ich definitiv niemals selbst drauf gekommen.

Vielen Dank für Eure Hilfe, Tipps und Eure Geduld!

dinu · 18. April 2017 um 22:47

Hallo,

ich möchte den Thread noch mal „aktivieren“, da ich das gleiche Problem habe und keinen so eleganten Lösungsansatz wie McHensy.

Ich habe von einer (funktionierenden) CCU2 auf einen Raspi umgestellt und folgende Voraussetzungen/Umgebung:

CCU Version 2.27.8.20170413
Homeatic mit Funk und Wired
IP-Symcon unlimited Version 4.1 vom 17.03.2017
RaspiMatic Version RaspberryMatic-2.27.8.20170413
IP-Symcon auf Windows 10

Habe das Problem auch mit CCU und RaspiMatic 2.25.15 gehabt und dann auf 2.27.8 umgeswitched.

Wenn ich im Homematic Konfigurator den Socket „von Hand“ aktiviere, dann funktioniert alles.
Nach etwa einer Minute kommen die o.g. Meldungen und es besteht keine Verbindung mehr.
Im Konfigurator ist dann auch zu sehen, dass ein Problem mit dem Socket besteht (Schnittstelle ist als fehlerhaft markiert).

Nach einem anderen Post habe ich mal die Einstellungen „Nutze HomeMatic Funk“, „Nutze HomeMatic Wired“ und „Nutze HomeMatic IP“ jeweils umgestellt und gespeichert und dann wieder auf die richtigen Werte gestellt habe, hatte ich für kurze Zeit den Effekt, dass nach „Wiederverbinden HomeMatic Socket“ die Verbindung tatsächlich wieder aktiviert wurde.
Nach geraumer Zeit hat das aber auch wieder dazu geführt, dass kein Connect mehr bestand.

Ich habe eine andere Installation in einer anderen Wohnung (nur Funk), bei der ich erfolgreich auf den Raspi (dort Version 2.25.15) umsteigen konnte.
Wie gesagt: das Problem besteht nicht, wenn ich die CCU2 anschließe, sondern nur beim Raspi.

Habt ihr eine Idee?

sokkederheld · 13. Juni 2017 um 20:00

Hatte das Problem auch, und zwar auf einer ansonsten völlig jungfräulichen Raspi-CCU2 und einem ebenso unbeflecktem IPS (Win10). Es ist überhaupt nur ein HM-Aktor angelernt, nämlich eine schnöde Schaltsteckdose.

Bei mir lag es daran, dass das Gerät aus irgendeinem Grunde zwei IP-Adressen zugewiesen hatte… also, nslookup gab zurück:

Name: homematic-raspi.fritz.box
Address: 192.168.179.21
Name: homematic-raspi.fritz.box
Address: 192.168.178.61

Jetzt fragt mich nicht, wodurch das zustande kam (WLAN ist auf dem Raspi nicht eingerichtet!) aber ich habe die beiden IP-Adressen angepingt und siehe da, nur die eine gibt Antwort.

Also diesejenige in der HM-Socket eingetragen und seitdem läuft es.

Das Webinterface läuft übrigens auch viel stabiler jetzt, seitdem ich die IP direkt in die Adresszeile eingebe.

Kann sein dass das ein sonderbarer Einzelfall bei mir ist, aber ich dachte ich teil’s mal…

Axel37 · 13. Juni 2017 um 20:19

irgendeinem Grunde zwei IP-Adressen zugewiesen

hatte der Raspi evtl noch ein DHCP-Lease (48h) aktiv, hast du eine feste IP zugewiesen ?
habe ich auch neulich auch gehabt, mein Slate hatte ständig 2 IP (via LAN), nachdem ich den Router & Slate neu gebootet hatte, war alles wieder ok