HomeMatic Socket zu RaspberryMatic instabil

T30 · 26. März 2018 um 21:48

Problemlösung:

Beim Einsatz von mehr als einer CCU sollte man den ominösen Satz "„Diese Einstellungen sollten nur geändert werden, wenn Sie genau wissen, was Sie tun.“ ignorieren und bei den verschiedenen HomeMatic Sockets unterschiedliche Ports bei „Ereignisse“ eintragen.
Erklärung siehe

Da das Protokoll der IP-Homematic Sensoren nicht über die bestehenden LAN-Adapter übertragen wird, muß ich den LAN-Adapter in meiner Garage durch eine CCU2 oder einen CCU-Emulator ersetzen.
Ich habe dafür RaspberryMatic auf einem RaspberryPi installiert (siehe)

Die Anbindung an IPS (Version ‚IP-Symcon 4.4, Raspberry Pi, 16.12.17, 0ce24b1b3093‘) auf einem anderen RaspberryPi über einen HomeMatic Socket funktioniert einwandfrei, auch die Statusänderungen des bisher einzigen angelernten Homematic IP Beschleunigungssensor HmIP-SAM kommen an.

Ich habe aber Probleme mit meinem IPS-Hauptsystem (Version IP-Symcon 4.20, 14.03.2017, 8feff3758e5c), das auf Win7 läuft.

Der angelegte HomeMatic Socket (IP-Adresse von RaspiB6) verabschiedet sich regelmäßig und wird kurze Zeit später wieder verbunden (Protokoll ab Erstellung des Sockets):

20:19:58 | 46276 | MESSAGE | HomeMatic Socket     | Erstelle...
20:19:58 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:20:23 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:20:23 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:20:23 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:21:28 | 46276 | ERROR   | TimerPool            | HomeMatic Socket (KeepAlive): Waiting for pong timed out
20:22:04 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:22:04 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
20:22:04 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
20:22:04 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:22:04 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:22:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [HomeMatic Socket] erfolgreich
20:22:58 | 46276 | ERROR   | TimerPool            | HomeMatic Socket (KeepAlive): Waiting for pong timed out
20:23:04 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:23:04 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
20:23:04 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
20:23:04 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:23:04 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:23:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [HomeMatic Socket] erfolgreich
20:24:28 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Waiting for pong timed out
20:25:04 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:25:04 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
20:25:04 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
20:25:04 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:25:04 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:25:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich

Angekreuzt sind „Nutze Homematik Funk“ und „Nutze Homematik IP“; die Port-Nummern hab ich unverändert gelassen.
Irgenwann wird auch ein HomeMatic Configurator erstellt:

20:34:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
20:34:59 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Waiting for pong timed out
20:35:04 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:35:04 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
20:35:04 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
20:35:04 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:35:04 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:35:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
20:35:24 | 24628 | MESSAGE | HomeMatic Configurator | Erstelle...
20:35:24 | 24628 | MESSAGE | HomeMatic Configurator | Einstellungen gespeichert
20:36:29 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Waiting for pong timed out
20:37:04 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
20:37:04 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
20:37:04 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
20:37:04 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
20:37:04 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
20:37:04 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
20:37:59 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Waiting for pong timed out

Der HomeMatic Configurator listet die Virtuellen Keys der RaspberryMatic und auch die Datenpunkte Homematic IP Beschleunigungssensor HmIP-SAM. Dafür ist auch ein HomeMatic Device angelegt und als übergeordnete Instanz ist RaspiB6 eingetragen, es kommen aber keine Daten an.

Hab ich irgendwas falsch konfiguriert ? (außer dass ich auf meinem IPS-Hauptsystem nicht die allerneueste Version habe).

Viele Grüsse
Harald

T30 · 26. März 2018 um 22:09

Habe gerade noch einen weiteren Test gemacht:

Ich hab den Socket der CCU2 (der natürlich auch auf einer anderen IP-Adresse läuft) geschlossen. Darauf kommen Daten auf dem Socket des RaspiB6 an

21:55:05 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
21:55:05 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
21:55:05 | 46276 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
21:55:05 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
21:55:05 | 46276 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
21:55:05 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
21:55:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Socket ist nicht verbunden
21:56:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
21:56:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
21:56:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Socket ist nicht verbunden
21:57:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
21:57:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
21:57:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Socket ist nicht verbunden
21:58:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
21:58:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
21:58:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Socket ist nicht verbunden
21:59:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
21:59:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
21:59:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Socket ist nicht verbunden
22:00:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
22:00:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich

Jetzt hab ich aber die Instabilität auf dem Socket der CCU2 (was viel schlimmer ist):

22:04:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
22:04:05 | 12183 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
22:04:05 | 12183 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
22:04:05 | 12183 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
22:04:05 | 12183 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
22:04:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
22:05:27 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Waiting for pong timed out
22:06:05 | 12183 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
22:06:05 | 12183 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
22:06:05 | 12183 | MESSAGE | HomeMatic Socket     | Stopping event dispatch thread...
22:06:05 | 12183 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
22:06:05 | 12183 | MESSAGE | HomeMatic Socket     | Creating event dispatch thread...
22:06:05 | 12183 | MESSAGE | Event Control        | Wiederverbinden [CCU2] erfolgreich
22:06:57 | 12183 | ERROR   | TimerPool            | CCU2 (KeepAlive): Waiting for pong timed out

Viele Grüsse
Harald

T30 · 27. März 2018 um 10:42

Hab gestern abend noch upgedated und habe jetzt auf dem Win7 Server

IP-Symcon 4.4, Windows x86, 19.02.2018, 36a7b6fcaa27

Es sind 3 HomeMatic Sockets eingetragen:

CCU1 mit IP 192.168.0.21 (seit ca. 2 Jahren ist der Socket geschlossen und die IP-Adresse ist unbenutzt, die zu verwendenden Geräte sind nicht angekreuzt, Ports unverändert übernommen)
CCU2 mit IP 192.168.0.22 (läuft seit ca. 2 Jahren problemlos, angekreuzt sind „Nutze Homematik Funk“ und „Nutze Homematik IP“; die Port-Nummern hab ich unverändert gelassen)
RaspiB6 mit IP 192.168.0.146 (Socket geschlossen, angekreuzt sind „Nutze Homematik Funk“ und „Nutze Homematik IP“; die Port-Nummern hab ich unverändert gelassen)

Heute morgen den HomeMatic Socket RaspiB6 nochmal versuchsweise geöffnet:

27.03.2018 10:03:41 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
27.03.2018 10:03:41 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
27.03.2018 10:03:41 | 46276 | MESSAGE | HomeMatic Socket     | Beende Ereignis-Thread...
27.03.2018 10:03:41 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
27.03.2018 10:03:41 | 46276 | MESSAGE | HomeMatic Socket     | Erstelle Ereignis-Thread...
27.03.2018 10:03:41 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
...
27.03.2018 10:04:50 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Warten auf "Pong" Antwort fehlgeschlagen
27.03.2018 10:05:25 | 17388 | DEBUG   | Archive Control      | Writing negative record size for variable 26246
27.03.2018 10:05:25 | 17388 | DEBUG   | Archive Control      | Writing negative record size for variable 19453
27.03.2018 10:05:25 | 17388 | DEBUG   | Archive Control      | Writing negative record size for variable 38831
27.03.2018 10:05:41 | 46276 | MESSAGE | HomeMatic Socket     | Einstellungen gespeichert
27.03.2018 10:05:41 | 46276 | MESSAGE | HomeMatic Socket     | Stoppe Eventserver...
27.03.2018 10:05:41 | 46276 | MESSAGE | HomeMatic Socket     | Beende Ereignis-Thread...
27.03.2018 10:05:41 | 46276 | MESSAGE | HomeMatic Socket     | Starte Eventserver...
27.03.2018 10:05:41 | 46276 | MESSAGE | HomeMatic Socket     | Erstelle Ereignis-Thread...
27.03.2018 10:05:41 | 46276 | MESSAGE | Event Control        | Wiederverbinden [RaspiB6 ] erfolgreich
27.03.2018 10:06:20 | 46276 | ERROR   | TimerPool            | RaspiB6  (KeepAlive): Warten auf "Pong" Antwort fehlgeschlagen

Es scheint einfach so zu sein, als könne mein System einen 2ten zugeschalteten HomeMatic Socket nicht korrekt bedienen (Gestern hatte ich CCU2 geschlossen und RaspiB6 geöffnet, bei nachträglichem Öffnen von CCU2 zeigte dieser Socket die Probleme).

Kann das jemand nachvollziehen ? (evtl Performance-Probleme?, eingesetzt ist hier ein HP ProLiant G7 MicroServer N54L 1P 2 GB-U SATA 250 GB) oder muss da doch irgendwas anders eingestellt werden?

Viele Grüsse
Harald

Powerfreddy · 27. März 2018 um 11:48

Wie sind denn die Port-Einstellungen?

Gruß
Bruno

T30 · 27. März 2018 um 18:22

Du meinst sicher die Nummern in dem Bereich der Konfigurationseinstellungen, der überschrieben ist mit
„Diese Einstellungen sollten nur geändert werden, wenn Sie genau wissen, was Sie tun. Ein möglicher Fall könnten mehrere vorhandene CCUs sein.“

Als ich jetzt nicht nur den ersten Satz gelesen habe (und früher gleich die Finger von diesen Einstellungen ließ), sondern auch den zweiten, fielen mir wie Otto Waalkes die Schuppen aus den Haaren

Nochmal Forensuche nach „Ereignis-Port“ (statt nach CCU wie bisher) brachte mir die Lösung:

Die Angaben zu den Ports für Radio/Wired/IP beziehen sich auf die einstellbare IP-Adresse des als „Host“ bezeichneten HM-Devices,
der Port für Ereignisse dagegen auf die unveränderliche IP-Adresse des richtigen Hosts (der IPS-Server), der als Ereignisserver bezeichnet wird. Und weil diese IP-Adresse für alle Sockets gleich ist, müssen die Ports unterschiedlich sein.

Port-Nummer für den 2ten Socket um +1 geändert und schon läufts

Aber das ist ja ganz logisch und jedem bekannt und bestimmt auch irgendwo in der Doku. Deshalb hat mir auch keiner einen Tipp gegeben :mad:

Jdenfalls vielen Dank für den Denkanstoss !!!

Viele Grüsse
Harald

Powerfreddy · 27. März 2018 um 18:59

Freut mich, daß es jetzt funktioniert …