CUxD nicht mit Homematic-CCU verbunden

[ferdik]

Hallo zusammen,

habe aktuell mit CUxD 2.11 unter RaspberryMatic (3.73.9.20231130) folgendes Problem:

Ohne bewusst etwas relevantes zu ändern ist mir heute aufgefallen, dass meine cuxd Geräte nicht mehr funktionieren. Die CUxD Status-Seite zeigt auch:

• Nicht mit HomeMatic-CCU 127.0.0.1:8183 verbunden!

• Nicht als RPC-Server von der CCU angefordert

Ich habe mittlerweile sehr oft neu gestartet, CUxD neu drüberinstalliert, komplett De- und Neuinstalliert, und sogar das ganze System auf eine neue SD-Karte gespielt - immer mit zweimaligen Neustarts dazwischen. Einige male war kurzzeitig wieder eine Verbindung da, aber nach einem weiteren Neustart ist dann jedesmal wieder keine Verbindung mehr möglich.

Die Firewall kann ich (meiner Meinung nach) als Fehlerquelle ausschließen (lauft ja alles auf 127.0.0.1). Unten noch das CUxD Syslog mit einer auffälligen "Connection timed out" Meldung, deren Ursache ich nicht nachvollziehen kann.

Aktuell weiß ich leider nicht weiter. Kann mir jemand bei der Ursachensuche weiterhelfen? Vielen Dank!

[...]
Dec 26 23:31:34 ccu daemon.info cuxd[1650]: connection to 127.0.0.1:8183 successfull!
Dec 26 23:31:41 ccu daemon.err cuxd[1650]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Dec 26 23:31:41 ccu daemon.warn cuxd[1650]: disable events to 127.0.0.1:31999[/i]

[uwe111]

Aktuell weiß ich leider nicht weiter. Kann mir jemand bei der Ursachensuche weiterhelfen? Vielen Dank!

Code: Alles auswählen

Dec 26 23:31:41 ccu daemon.err cuxd[1650]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Dec 26 23:31:41 ccu daemon.warn cuxd[1650]: disable events to 127.0.0.1:31999

Der CUxD kann keine TCP Verbindung zur Logikschicht aufbauen. Für mich sieht es nach einem Firewall Problem aus.

Viele Grüße

Uwe

[ferdik]

Vielen Dank für deine schnelle Antwort! Hatte auch alles mal mit Vollzugriff bei den Firewall-Regeln getestet - ohne Erfolg.

Ich konnte das Problem durch zurückspielen verschiedener Backup-Stände und ausführliches testen vermeintlich auf das Anlegen von Heizungsgruppen zurückführen:

Hatte vor kurzem insgesamt drei neue Gruppen mit jeweils einem HM-CC-RT-DN und einem HM-Sec-SCo angelegt. Zusätzlich bestanden bei mir schon zwei andere Heizungsgruppen.

Das Backup noch ohne die drei neuen Gruppe funktionierte einwandfrei. Sobald ich die Gruppen dann wieder angelegt hatte, konnte CUxD wieder keine Verbindung aufbauen.

Ich habe die Gruppen jetzt aufgelöst und manuell die Direktverknüpfungen angelegt. Damit funktioniert alles problemlos.

Gibt es dafür eine plausible Erklärung oder eine Möglichkeit den Fehler noch besser zu analysieren? Vielen Dank!

[ferdik]

Hallo zusammen, leider ist mein beschriebenes Problem offensichtlich doch nicht gelöst. Es verhält sich bei mir aktuell wie folgt:

Spiele ich ein neues Backup ein oder fahre ich die CCU komplett herunter (incl. Strom weg) und wieder neu hoch, werden die Verbindungen komplett korrekt hergestellt:

Status-Seite:
=> Erfolgreich mit HomeMatic-CCU 127.0.0.1:8183 verbunden!
=> als RPC-Server(INIT) von HomeMatic-CCU (2806) angefordert!

Logfile:

Code: Alles auswählen

Mar 16 13:57:39 ccu daemon.info cuxd[1632]: CUx-Daemon(2.11) on CCU(3.73.9.20240130) start PID:1632
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write_pid /var/run/cuxd.pid [1632]
Mar 16 13:57:39 ccu user.info cuxd: started cux-daemon
Mar 16 13:57:39 ccu user.notice root: dutycycle.tcl[1654] 32001,32010 /var/cache/cuxd_dutycycle.txt 120 started
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: system(extra/dutycycle start)
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12564 update(-162s):Sat Mar 16 13:54:57 2024
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: 14 device-paramset(s) loaded ok!
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write_proxy /var/cache/cuxd_proxy.ini (1632 /usr/local/addons/cuxd/ 2.11 3.73.9.20240130 0)
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: add interface 'CUxD'
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 13:57:39 ccu daemon.info cuxd[1632]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Mar 16 13:59:04 ccu daemon.warn cuxd[1632]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Mar 16 13:59:08 ccu daemon.warn cuxd[1632]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Mar 16 13:59:28 ccu daemon.info cuxd[2295]: system(echo none >/sys/class/leds/led0/trigger) exit(1) 0s
Mar 16 13:59:28 ccu daemon.info cuxd[2297]: system(echo none >/sys/class/leds/led1/trigger) exit(1) 0s
Mar 16 13:59:42 ccu daemon.info cuxd[1632]: INIT 'xmlrpc_bin://127.0.0.1:31999' '2806'
Mar 16 13:59:42 ccu daemon.info cuxd[1632]: RPC-server from HM-CCU (2806) registered!
Mar 16 13:59:43 ccu daemon.info cuxd[1632]: setup CUX9000001:1 read(CUX-SYSTEM:0) => CUxD.CUX-SYSTEM:0 (SYSTEM) DP(|CPU10|LAVG1M|LAVG5M|LAVG15M|PROCS|CUXDMEM|MEMUSED|)
Mar 16 13:59:43 ccu daemon.info cuxd[1632]: setup CUX9000002:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 13:59:43 ccu daemon.info cuxd[1632]: setup CUX9000003:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 13:59:43 ccu daemon.info cuxd[1632]: setup CUX9000004:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 13:59:43 ccu daemon.info cuxd[1632]: connection to 127.0.0.1:8183 successfull!

Auch ein manueller Restart von cuxd funktioniert zu diesem Zeitpunkt fehlerfrei, es wird wieder alles verbunden.
Nach einem Neustart der CCU (also über Zentralenwartung => Neustart) funktioniert die CCU Verbindung noch, aber die RPC Verbindung nicht:

Status-Seite:
=> Erfolgreich mit HomeMatic-CCU 127.0.0.1:8183 verbunden!
=> Nicht als RPC-Server von der CCU angefordert!

Logfile:

Code: Alles auswählen

Mar 16 14:05:13 ccu user.info cuxd: started cux-daemon
Mar 16 14:05:13 ccu user.notice root: dutycycle.tcl[1657] 32001,32010 /var/cache/cuxd_dutycycle.txt 120 started
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: system(extra/dutycycle start)
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12582 update(-64s):Sat Mar 16 14:04:09 2024
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: 14 device-paramset(s) loaded ok!
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: write_proxy /var/cache/cuxd_proxy.ini (1635 /usr/local/addons/cuxd/ 2.11 3.73.9.20240130 0)
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: add interface 'CUxD'
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 14:05:13 ccu daemon.info cuxd[1635]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Mar 16 14:06:43 ccu daemon.warn cuxd[1635]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Mar 16 14:06:47 ccu daemon.warn cuxd[1635]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Mar 16 14:07:08 ccu daemon.info cuxd[2303]: system(echo none >/sys/class/leds/led0/trigger) exit(1) 0s
Mar 16 14:07:08 ccu daemon.info cuxd[2305]: system(echo none >/sys/class/leds/led1/trigger) exit(1) 0s
Mar 16 14:07:15 ccu daemon.info cuxd[1635]: INIT 'xmlrpc_bin://127.0.0.1:31999' '2806'
Mar 16 14:07:15 ccu daemon.info cuxd[1635]: RPC-server from HM-CCU (2806) registered!
Mar 16 14:07:23 ccu daemon.err cuxd[1635]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Mar 16 14:07:30 ccu daemon.err cuxd[1635]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Mar 16 14:07:30 ccu daemon.warn cuxd[1635]: disable events to 127.0.0.1:31999
Mar 16 14:07:30 ccu daemon.info cuxd[1635]: setup CUX9000001:1 read(CUX-SYSTEM:0) => CUxD.CUX-SYSTEM:0 (SYSTEM) DP(|CPU10|LAVG1M|LAVG5M|LAVG15M|PROCS|CUXDMEM|MEMUSED|)
Mar 16 14:07:30 ccu daemon.info cuxd[1635]: setup CUX9000002:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 14:07:30 ccu daemon.info cuxd[1635]: setup CUX9000003:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 14:07:30 ccu daemon.info cuxd[1635]: setup CUX9000004:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 14:07:30 ccu daemon.info cuxd[1635]: connection to 127.0.0.1:8183 successfull!

Führe ich dann einen manuellen Restart von cuxd durch, funktioniert gar keine Verbindung mehr:

Status-Seite:
=> Nicht mit HomeMatic-CCU 127.0.0.1:8183 verbunden!
=> Nicht als RPC-Server von der CCU angefordert!

Logfile:

Code: Alles auswählen

Mar 16 14:11:06 ccu daemon.info cuxd[1635]: CUx-Daemon restart(1)
Mar 16 14:11:06 ccu daemon.info cuxd[1635]: remove(/var/cache/cuxd_proxy.ini)
Mar 16 14:11:06 ccu daemon.info cuxd[1635]: save paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12581
Mar 16 14:11:06 ccu daemon.info cuxd[4042]: CUx-Daemon(2.11) on CCU(3.73.9.20240130) start PID:4042
Mar 16 14:11:06 ccu daemon.info cuxd[4042]: write_pid /var/run/cuxd.pid [4042]
Mar 16 14:11:08 ccu user.notice root: dutycycle.tcl[4059] 32001,32010 /var/cache/cuxd_dutycycle.txt 120 started
Mar 16 14:11:08 ccu daemon.info cuxd[4042]: system(extra/dutycycle start)
Mar 16 14:11:08 ccu daemon.info cuxd[4042]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12581 update(-2s):Sat Mar 16 14:11:06 2024
Mar 16 14:11:08 ccu daemon.info cuxd[4042]: 14 device-paramset(s) loaded ok!
Mar 16 14:11:08 ccu daemon.info cuxd[4042]: write_proxy /var/cache/cuxd_proxy.ini (4042 /usr/local/addons/cuxd/ 2.11 3.73.9.20240130 0)
Mar 16 14:11:08 ccu daemon.warn cuxd[4042]: init() '' not supported

Hat denn jemand eine Idee zur Ursachensuche oder gar Problemlösung? Vielen Dank!

[chka]

zeig mal deine Firewall Einstellungen der ccu

[ferdik]

Hab testweise alles mal auf Vollzugriff gestellt, auch da bestehen die identischen Probleme

Portfreigaben:

Code: Alles auswählen

80;
8001;
8002;
8082;
8181;
9874;
9875;
9876;
9877;
9878;
9879;
9880;
9881;
9882;
9883;
2001;
2010;
9292

[chka]

8701;
6556

sollten reichen

ist die Ip 127.0.0.1; in der Ip Liste?

[ferdik]

Habe beide Ports und die IP ergänzt (war nicht eingetragen).
Leider verbindet sich CUxD nach einem manuellen Restart nach wie vor nicht.

[chka]

ist die Ip 127.0.0.1; in der Ip Liste?

[ferdik]

Ja, habe ich ergänzt.

Aktuelles Log:

Code: Alles auswählen

Mar 16 17:16:40 ccu daemon.info cuxd[3229]: CUx-Daemon restart(1)
Mar 16 17:16:40 ccu daemon.info cuxd[3229]: remove(/var/cache/cuxd_proxy.ini)
Mar 16 17:16:40 ccu daemon.info cuxd[3229]: save paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12598
Mar 16 17:16:40 ccu daemon.info cuxd[4228]: CUx-Daemon(2.11.1) on CCU(3.73.9.20240130) start PID:4228
Mar 16 17:16:40 ccu daemon.info cuxd[4228]: write_pid /var/run/cuxd.pid [4228]
Mar 16 17:16:41 ccu user.notice root: dutycycle.tcl[4241] 32001,32010 /var/cache/cuxd_dutycycle.txt 120 started
Mar 16 17:16:41 ccu daemon.info cuxd[4228]: system(extra/dutycycle start)
Mar 16 17:16:41 ccu daemon.info cuxd[4228]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12598 update(-1s):Sat Mar 16 17:16:40 2024
Mar 16 17:16:41 ccu daemon.info cuxd[4228]: 14 device-paramset(s) loaded ok!
Mar 16 17:16:41 ccu daemon.info cuxd[4228]: write_proxy /var/cache/cuxd_proxy.ini (4228 /usr/local/addons/cuxd/ 2.11.1 3.73.9.20240130 0)
Mar 16 17:16:41 ccu daemon.warn cuxd[4228]: init() '' not supported

Und nochmal ein neues Log, nach Reboot der CCU:

Code: Alles auswählen

Mar 16 17:25:16 ccu daemon.info cuxd[1641]: system(extra/dutycycle start)
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:12567 update(-64s):Sat Mar 16 17:24:12 2024
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: 14 device-paramset(s) loaded ok!
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: write_proxy /var/cache/cuxd_proxy.ini (1641 /usr/local/addons/cuxd/ 2.11.1 3.73.9.20240130 0)
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: add interface 'CUxD'
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Mar 16 17:25:16 ccu daemon.info cuxd[1641]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Mar 16 17:26:43 ccu daemon.warn cuxd[1641]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Mar 16 17:26:46 ccu daemon.warn cuxd[1641]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Mar 16 17:27:09 ccu daemon.info cuxd[2328]: system(echo none >/sys/class/leds/led0/trigger) exit(1) 0s
Mar 16 17:27:09 ccu daemon.info cuxd[2330]: system(echo none >/sys/class/leds/led1/trigger) exit(1) 0s
Mar 16 17:27:16 ccu daemon.info cuxd[1641]: INIT 'xmlrpc_bin://127.0.0.1:31999' '2806'
Mar 16 17:27:16 ccu daemon.info cuxd[1641]: RPC-server from HM-CCU (2806) registered!
Mar 16 17:27:24 ccu daemon.err cuxd[1641]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Mar 16 17:27:31 ccu daemon.err cuxd[1641]: sendbinrpc(127.0.0.1:31999) connect(7s) Connection timed out
Mar 16 17:27:31 ccu daemon.warn cuxd[1641]: disable events to 127.0.0.1:31999
Mar 16 17:27:31 ccu daemon.info cuxd[1641]: setup CUX9000001:1 read(CUX-SYSTEM:0) => CUxD.CUX-SYSTEM:0 (SYSTEM) DP(|CPU10|LAVG1M|LAVG5M|LAVG15M|PROCS|CUXDMEM|MEMUSED|)
Mar 16 17:27:31 ccu daemon.info cuxd[1641]: setup CUX9000002:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 17:27:31 ccu daemon.info cuxd[1641]: setup CUX9000003:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 17:27:31 ccu daemon.info cuxd[1641]: setup CUX9000004:1 read(CUX-SYSTEM:1) => CUxD.CUX-SYSTEM:1 (SYSTEM) DP(DC_..........)
Mar 16 17:27:31 ccu daemon.info cuxd[1641]: connection to 127.0.0.1:8183 successfull!
Mar 16 17:27:43 ccu daemon.err cuxd[1641]: CUX4000003:2.STATE parameter is not writeable!
Mar 16 17:27:43 ccu daemon.err cuxd[1641]: CUX4000003:3.STATE parameter is not writeable!

Kann es sein, dass auf der CCU irgendwas schreibgeschützt ist, was nicht sein sollte? Hab den Eindruck, das ganze Problem ist nach einem unclean Shutdown der CCU gekommen...