Hallo @nicx,
das Log zeigt leider den falschen Zeitpunkt ;-). Dort ist das Anlernpaket nicht sichtbar und es wird auch kein Paket gesendet. Aber das macht nichts. Der Anlernvorgang war einfach noch nicht abgeschlossen. Wenn du das Ganze mit den anderen Sensoren wiederholst, müsste alles passen.
Viele Grüße
Sathya
Hallo,
ich beobachte leider ähnliche Auffälligkeiten.
Da sich bei mir im Setup aber einiges geändert hat, bin ich selber noch auf der Ursache für diese Meldungen.
Stundenlang tauchen die Meldungen nicht auf, dann aus heiterem Himmel in unterschiedlichen Abständen vermehrt.
Zunächst hatte ich die CCU im Verdacht, die ich noch benötige um einen flammneuen HMip-SPI anzusteuern. Der funkt zwar noch unter der Decke aber ohne CCU. Den werde ich nun auch mal deaktivieren. Jedes mal wenn ich die CCU abgeschaltet hatte, waren die Meldungen weg. Die beiden HM-CFG-LAN sind dann erstmal stabil gewesen.
Nun habe ich Tage später zwei HM-Sec-RHS installiert. Beide angelernt, beide funken zuverlässig, hinsichtlich Benutzerinteraktion an den Fenstern. Bis auf die Meldungen die nun wieder vermehrt auftauchen. Zwar funkt der HMip-SPI noch aber ohne Gegenstück.
Da Homegear nach den “no response”-Meldungen irgendwann alle Verbindungen abbaut und neu startet, musste ich meinem HomeAssistant den periodischen Reconnect beibringen, wenn HomeAssistant für eine Zeit von 2 Minuten keine Meldungen mehr bekam. So gibt es Tage da komme ich mit einem Reconnect aus und andere Tag da gibt es 13 oder mehr dieser protokollierten Disconnects. Schaue ich mir die Homegear Logs dazu an, sehe ich passend immer wieder die “no Response”-Meldungen mit Reinitialisierungen von Homegear.
Ich werde nun also die beiden HM-Sec-RHS und den HMip-SPI mal stromlos machen und dann berichten ob ich die Meldungen noch sehe.
Nun also nach 8h erste weitere Erkenntnisse. 6 weitere reconnects von HomeAssistant notwendig, sprich 6 mal “Absturz” des Homegear.
Mit der Ansteuerung eines Elementes läßt sich der Fehler zuverlässig reproduzieren:
04/17/18 08:38:47.966 RPC Server (Port 2001): Info: Connection from ::ffff:172.16.112.101:35628 accepted. Client number: 4077
04/17/18 08:38:47.966 RPC Server (Port 2001): Info: RPC server client id for client number 4077 is: 7
04/17/18 08:38:47.967 RPC Server (Port 2001): Info: Client number 4077 is calling RPC method: setValue (1) Parameters:
(String) JEQ0198459:1
(String) LEVEL
(Float) 1
04/17/18 08:38:47.967 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Office-LEQ0985852”: Info: Sending (HM-CFG-LAN-Office-LEQ0985852): 0E16A011FD03761B49780201C800A0
04/17/18 08:38:48.054 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -77 dBm): 0E16A011FD03761B49780201C800A0
04/17/18 08:38:48.178 HomeMatic BidCoS packet received (HM-CFG-LAN-Office-LEQ0985852, RSSI: -82 dBm): 0F1680021B4978FD0376010130105128
04/17/18 08:38:48.178 Module HomeMatic BidCoS: Info: Queue 144 is empty and there are no pending queues.
04/17/18 08:38:48.179 Module HomeMatic BidCoS: Info: DIRECTION on channel 1 of HomeMatic BidCoS peer 9 with serial number JEQ0198459 was set to 0x01.
04/17/18 08:38:48.179 Module HomeMatic BidCoS: Info: ERROR_OVERHEAT on channel 1 of HomeMatic BidCoS peer 9 with serial number JEQ0198459 was set to 0x00.
04/17/18 08:38:48.180 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -51 dBm): 0F1680021B4978FD0376010130105128
04/17/18 08:38:51.272 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -42 dBm): 0D04A6103D126BFD037606010000
04/17/18 08:38:51.273 Module HomeMatic BidCoS: Info: ERROR on channel 1 of HomeMatic BidCoS peer 14 with serial number MEQ0810745 was set to 0x00.
04/17/18 08:38:51.273 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 14 with serial number MEQ0810745 was set to 0x00.
04/17/18 08:38:51.274 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 14 with serial number MEQ0810745 was set to 0x00.
04/17/18 08:38:51.274 Module HomeMatic BidCoS: Info: LOWBAT on channel 0 of HomeMatic BidCoS peer 14 with serial number MEQ0810745 was set to 0x00.
04/17/18 08:38:51.854 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -50 dBm): 0F17A4101B4978FD03760601C80080C8
04/17/18 08:38:51.968 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -77 dBm): 0A178002FD03761B497800
04/17/18 08:38:56.531 RPC Server (Port 2001): Info: Connection from ::ffff:172.16.112.101:35658 accepted. Client number: 4087
04/17/18 08:38:56.532 RPC Server (Port 2001): Info: RPC server client id for client number 4087 is: 8
04/17/18 08:38:56.532 RPC Server (Port 2001): Info: Client number 4087 is calling RPC method: setValue (1) Parameters:
(String) JEQ0198459:1
(String) LEVEL
(Float) 0
04/17/18 08:38:56.533 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Office-LEQ0985852”: Info: Sending (HM-CFG-LAN-Office-LEQ0985852): 0E17A011FD03761B497802010000A0
04/17/18 08:38:56.620 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -78 dBm): 0E17A011FD03761B497802010000A0
04/17/18 08:38:56.745 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -50 dBm): 0F1780021B4978FD03760101C72052C8
04/17/18 08:38:59.649 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -50 dBm): 0F18A4101B4978FD0376060100008000
04/17/18 08:38:59.650 Info: Changing interface of peer 9 to HM-CFG-LAN-Living-KEQ1023403, because the reception is better.
04/17/18 08:38:59.764 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -77 dBm): 0A188002FD03761B497800
04/17/18 08:39:02.745 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
04/17/18 08:39:02.745 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 9). Retrying…
04/17/18 08:39:02.846 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Living-KEQ1023403”: Info: Sending (HM-CFG-LAN-Living-KEQ1023403): 0E17A011FD03761B497802010000A0
04/17/18 08:39:03.056 HomeMatic BidCoS packet received (HM-CFG-LAN-Living-KEQ1023403, RSSI: -51 dBm): 0F1780021B4978FD0376010100003400
04/17/18 08:39:03.057 Module HomeMatic BidCoS: Info: Queue 146 is empty and there are no pending queues.
04/17/18 08:39:03.059 Module HomeMatic BidCoS: Info: DIRECTION on channel 1 of HomeMatic BidCoS peer 9 with serial number JEQ0198459 was set to 0x00.
04/17/18 08:39:03.059 Module HomeMatic BidCoS: Info: ERROR_OVERHEAT on channel 1 of HomeMatic BidCoS peer 9 with serial number JEQ0198459 was set to 0x00.
04/17/18 08:39:46.991 RPC Server (Port 2001): Info: Connection from ::ffff:172.16.112.101:35762 accepted. Client number: 4089
04/17/18 08:39:46.992 RPC Server (Port 2001): Info: RPC server client id for client number 4089 is: 9
04/17/18 08:39:46.993 RPC Server (Port 2001): Info: Client number 4089 is calling RPC method: setValue (1) Parameters:
(String) JEQ0198459:1
(String) LEVEL
(Float) 0
04/17/18 08:39:46.993 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Living-KEQ1023403”: Info: Sending (HM-CFG-LAN-Living-KEQ1023403): 0E18A011FD03761B497802010000A0
04/17/18 08:39:49.999 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
04/17/18 08:39:50.000 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 9). Retrying…
04/17/18 08:39:50.100 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Living-KEQ1023403”: Info: Sending (HM-CFG-LAN-Living-KEQ1023403): 0E18A011FD03761B497802010000A0
04/17/18 08:39:53.106 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
04/17/18 08:39:53.106 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 9). Retrying…
04/17/18 08:39:53.206 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Living-KEQ1023403”: Info: Sending (HM-CFG-LAN-Living-KEQ1023403): 0E18A011FD03761B497802010000A0
04/17/18 08:39:55.026 Critical: Physical interface’s (HM-CFG-LAN-Office-LEQ0985852) lifetick was not updated for more than 60 seconds.
04/17/18 08:39:55.125 Critical: Killing child process.
04/17/18 08:39:55.138 Info: Process with id 27004 ended.
04/17/18 08:39:55.139 Homegear was terminated. Restarting (1)…
04/17/18 08:40:05.127 Homegear was terminated. Restarting (2)…
04/17/18 08:40:05.130 Info: Setting maximum thread priority to “0” for user with id 0 and group with id 0.
04/17/18 08:40:05.131 Info: Maximum thread priority now is “0”.
04/17/18 08:40:05.229 Info: Backing up database…
04/17/18 08:40:05.365 Initializing database…
04/17/18 08:40:05.368 Info: Loading licensing module mod_licensing.so
04/17/18 08:40:05.369 Info: Loading licensing module mod_easy_licensing.so
04/17/18 08:40:05.370 Info: Loading family module mod_miscellaneous.so
04/17/18 08:40:05.370 /etc/homegear/families/miscellaneous.conf
04/17/18 08:40:05.370 Info: Loading family module mod_homematicbidcos.so
04/17/18 08:40:05.374 /etc/homegear/families/homematicbidcos.conf
04/17/18 08:40:05.374 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Living-KEQ1023403”: Info: Enabling AES encryption for communication with HM-CFG-LAN.
04/17/18 08:40:05.374 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “HM-CFG-LAN-Office-LEQ0985852”: Info: Enabling AES encryption for communication with HM-CFG-LAN.
04/17/18 08:40:05.374 Info: Setting up physical interfaces and GPIOs…
04/17/18 08:40:05.374 Info: Dropping privileges to user homegear (106) and group homegear (110)
04/17/18 08:40:05.374 Info: Homegear is (now) running as user with id 106 and group with id 110.
04/17/18 08:40:05.377 Starting script engine server…
04/17/18 08:40:05.380 Initializing licensing controller…
04/17/18 08:40:05.539 Loading licensing controller data…
04/17/18 08:40:05.539 Loading devices…
04/17/18 08:40:05.539 Loading XML RPC devices…
04/17/18 08:40:05.666 Loading device 5
Hier sieht man 3 Schaltvorgänge. Einmal Device eingeschaltet, dann kurz gewartet und Device abgeschaltet. Da kam keine Rückmeldung, also nochmals ausgeschaltet. Darauf hin der Crash.
Es liegt also auch bei mir nicht im HM-Sec-RHS und auch nicht am funkenden HMip-SPI.
Ich werde die Devices heute einmal ablernen und weiter beobachten und berichten.
Hallo @Krys1976,
das ist ein bekanntes Problem. HM-IP-Pakete sind länger als BidCoS-Pakete. Bei Paketen ab einer bestimmten Länge ist früher der HM-CFG-LAN komplett abgestürzt, so dass dieser einmal stromlos gemacht werden musste. In aktuellen Firmwareversionen stürzt er immer noch ab. Der Absturz wird aber vom Watchdog erkannt und der HM-CFG-LAN startet neu. Das lässt sich leider nur von eQ-3 beheben. Ich würde ein anderes Kommunikationsmodul empfehlen. Am besten funktionieren die freien (CC1101, CUL, …). Das LAN-Gateway stürzt bei mir auch von Zeit zu Zeit mal ab und ganz problemlos läuft der HM-MOD-RPI-PCB meiner Erfahrung nach auch nicht.
Viele Grüße
Sathya
Darf ich Werbung machen? 
https://allgeek.de/2017/09/23/cc1101-spi-adapter-mit-u-fl-antennenbuchse/
Was heißt den von Zeit zu Zeit quantitativ? Im Verhältnis zu wievielen HM-Componenten?
Bin jetzt etwas hin und her gerissen, entweder HM-LAN-GWs oder zwei Raspberries mit 1101 …
Bei einer Installation hier mit etwa 100 Geräten etwa ein Mal alle 6 Monate.
@sathya Leider tritt das Problem immer wieder erneut auf, d.h. ich muss täglich durchs Haus rennen und den Anlernknopf drücken 
Hier das heutige Log:
04/20/18 07:11:01.226 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 07:11:05.225 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 07:30:05.806 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1091A001FD130928C21800040000000000
04/20/18 07:30:36.338 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 10BAA001FD130928C15D00040000000000
04/20/18 08:08:17.609 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1091A001FD130928C21800040000000000
04/20/18 08:08:46.705 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 10BAA001FD130928C15D00040000000000
04/20/18 08:22:19.276 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 08:22:32.747 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 08:34:23.168 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 0BDDA001FD1309234ED10203
04/20/18 09:54:43.670 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 09:55:04.416 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:21:59.781 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:22:29.032 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:55:39.995 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:57:46.679 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:59:25.685 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000
04/20/18 11:59:42.186 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-OG": Info: No response to packet after 3 tries: 1054A001FD130926AB3000040000000000Poste mal bitte das Homegearlog auf Loglevel 4. Die Fehlermeldungen sagen leider nichts über die Ursache des Problems.
Hallo @nicx,
kannst du mir noch einmal nur den Logabschnitt vom Drücken des Anlernknopfes schicken :-P. Vermutlich müsste ein queues clear reichen. Danach sollte alles funktionieren.
Viele Grüße
Sathya
