Homematic via LAN-GW: lesen geht noch, schreiben führt zu "Ignoring too small packet"

Seit einiger Zeit habe ich mit meinem homegear Setup das Problem, dass homegear zwar noch von den Geräten lesen kann (Status-Updates der Devices kommen über das LAN-Gateay rein), aber ich kann keine Einstellungen mehr ändern. Hier mal der Versuch, bei einem Thermostat die Temperatur zu setzen:

05/27/19 20:06:51.042 RPC Server (Port 2001): Info: Client number 11 is calling RPC method: setValue (1) Parameters:
(Integer) 2
(Integer) 4
(String) SET_TEMPERATURE
(String) 22
(Boolean) 1
05/27/19 20:06:51.043 Module HomeMatic BidCoS: Debug: SET_TEMPERATURE of peer 2 with serial number LEQ0780016:4 was set to 2C.
05/27/19 20:06:51.044 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Sending (My-HM-CFG-LAN): 0AF9B01100AFFE2DD41280
05/27/19 20:06:51.044 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): SFA793EA4,00,00000000,01,FFFFFFFFA5C71591,F9B01100AFFE2DD41280
05/27/19 20:06:51.693 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): RFA793EA4,0008,00000000,FF,7FFF,59
05/27/19 20:06:51.693 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Ignoring too small packet: 59
05/27/19 20:06:53.076 RPC Server (Port 2001): Debug: Joining read thread of client 0
05/27/19 20:06:53.077 RPC Server (Port 2001): Debug: Client 0 removed.
05/27/19 20:06:53.077 RPC Server (Port 2001): Debug: Joining read thread of client 1
05/27/19 20:06:53.077 RPC Server (Port 2001): Debug: Client 1 removed.
05/27/19 20:06:53.599 RPC Server (Port 2001): Response:
(Struct length=2)
{
[faultCode] (Integer) -100
[faultString] (String) No answer from device.
}
05/27/19 20:06:53.600 RPC Server (Port 2001): Response packet: HTTP/1.1 200 OK
Connection: Keep-Alive
Content-Type: text/xml
Content-Length: 248

<?xml version="1.0"?><member faultCode-100<member faultStringNo answer from device.</value

05/27/19 20:06:53.624 RPC Server (Port 2001): Debug: Connection to client number 11 closed (3).
05/27/19 20:06:53.630 Info: Script with id 1 finished with exit code 0
05/27/19 20:06:54.051 Module HomeMatic BidCoS: Debug: Deleting queue 0 for BidCoS peer with address 0x2DD412
05/27/19 20:06:54.051 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
05/27/19 20:06:54.051 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 2). Retrying…
05/27/19 20:06:54.151 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Sending (My-HM-CFG-LAN): 0AF9B01100AFFE2DD41280
05/27/19 20:06:54.152 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): SFA794AC7,00,00000000,01,FFFFFFFFA5C721B4,F9B01100AFFE2DD41280
05/27/19 20:06:54.794 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): RFA794AC7,0008,00000000,FF,7FFF,1B
05/27/19 20:06:54.795 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Ignoring too small packet: 1B
05/27/19 20:06:57.159 Module HomeMatic BidCoS: Debug: Deleting queue 1 for BidCoS peer with address 0x2DD412
05/27/19 20:06:57.159 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
05/27/19 20:06:57.177 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 2). Retrying…
05/27/19 20:06:57.260 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Sending (My-HM-CFG-LAN): 0AF9B01100AFFE2DD41280
05/27/19 20:06:57.260 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): SFA7956EC,00,00000000,01,FFFFFFFFA5C72DD9,F9B01100AFFE2DD41280
05/27/19 20:06:57.903 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): RFA7956EC,0008,00000000,FF,7FFF,DD
05/27/19 20:06:57.903 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Ignoring too small packet: DD

Hat jemand eine Idee, was das Problem sein könnte? Ich habe homegear auf einem raspbian (9.9) in Version 0.7.39-2773 installiert. Ich glaube, das Problem kam mit einem Update von homegear vor einiger Zeit, aber ganz sicher kann ich das nicht sagen. Bis dahin hat das Setup sehr stabil für über zwei Jahre funktioniert (inklusive Updates). Wenn man sich in der homegear CLI die homegear Geräte anschaut, steht dort für alle bei “Config Pending” und “Unreachable” jeweils “No”.

Ich habe bisher noch kein Gerät entfernt/zurückgesetzt und komplett neu gepaired. Ich hoffe, dass das unterm Strich nicht die Lösung sein wird, die Arbeit würde ich mir gerne sparen :slight_smile:

Grüße, Rudi

Achso und noch als kleiner Nachtrag: man sieht die Meldungen erst ab “Info”-Level aufwärts. Bin ich der einzige, der das auch als “Warning” einstufen würde? Falls nicht, würde ich da mal einen Feature Request bzw. ein Issue zu aufmachen. Normalerweise läuft homegear bei mir mit Log Level 3 (Warning), um nicht unnötig auf der SD Karte herumzuschreiben.

Hat niemand eine Idee? Dann muss ich wohl alles mal zurücksetzen und neu anlernen :confused:
Ich berichte dann hier, was dabei herausgekommen ist.

Es klingt grundsätzlich nach diesem Problem:
https://forum.homegear.eu/t/homegearopenhab-24-%E2%80%9Cno-answer-from-device-sending-setvalue%E2%80%9D/2723/28

@sathya könnte es das gleiche Thema sein? Du verweist da auf eine “max.conf” - die Datei gibt es bei mir allerdings nirgends unterhalb von /etc/homegear. Kannst du die Lösung bzw. Ursache ggf. noch ein wenig weiter ausführen?

Sorry, dass dir bis jetzt keiner helfen konnte. Ich leider auch nicht.

Die max.conf ist Teil von homegear-max für die Max!-Geräte - hat also mit Homematic nichts zu tun.
Schlussendlich muss sich das aber @sathya mal angucken…

Hier nochmal ein Nachtrag. Hab Pairing aktiviert und versucht, eine Remote (HM-RC-8) anzulernen. Hat auch nicht funktioniert mit folgendem Ergebnis:

06/03/19 19:29:10.007 IPC Server: Info: Client number 0 is calling RPC method: cliFamilyCommand
(Integer64) 0
(String) pon
06/03/19 19:29:10.008 IPC Server: Debug: CLI client 0 is executing family command: pon
06/03/19 19:29:10.009 IPC Server: Response:
(String) Pairing mode enabled for 60 seconds.

06/03/19 19:29:14.005 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): E283C90,0000,C9B2A0EB,FF,FFC4,938670283C9000000000E144
06/03/19 19:29:14.005 Debug (My-HM-CFG-LAN): Packet 0C938670283C9000000000E144 enters raisePacketReceived.
06/03/19 19:29:14.006 Debug (My-HM-CFG-LAN): Packet 0C938670283C9000000000E144 is now passed to the EventHandler.
06/03/19 19:29:14.005 HomeMatic BidCoS packet received (My-HM-CFG-LAN, RSSI: -60 dBm): 0C938670283C9000000000E144
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): Getting peer for packet 0C938670283C9000000000E144.
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): Packet 0C938670283C9000000000E144 is now passed to checkForBestInterface.
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): checkForBestInterface finished.
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): Packet 0C938670283C9000000000E144 is now passed to _receivedPackets.set.
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): _receivedPackets.set finished.
06/03/19 19:29:14.006 Devlog (My-HM-CFG-LAN): Packet 0C938670283C9000000000E144 is now passed to the peer.
06/03/19 19:29:14.008 Module HomeMatic BidCoS: Info: HUMIDITY on channel 1 of HomeMatic BidCoS peer 7 with serial number LEQ0122852 was set to 0x44.
06/03/19 19:29:14.008 Module HomeMatic BidCoS: Info: TEMPERATURE on channel 1 of HomeMatic BidCoS peer 7 with serial number LEQ0122852 was set to 0x00E1.
06/03/19 19:29:14.009 Debug (My-HM-CFG-LAN): Packet processing of packet 0C938670283C9000000000E144 took 3 ms.
06/03/19 19:29:17.596 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): E6C115D,0000,C9B2AEEC,FF,FFD4,0684006C115D0000001100DA5045513139313837373440080000
06/03/19 19:29:17.596 Debug (My-HM-CFG-LAN): Packet 1A0684006C115D0000001100DA5045513139313837373440080000 enters raisePacketReceived.
06/03/19 19:29:17.596 Debug (My-HM-CFG-LAN): Packet 1A0684006C115D0000001100DA5045513139313837373440080000 is now passed to the EventHandler.
06/03/19 19:29:17.596 HomeMatic BidCoS packet received (My-HM-CFG-LAN, RSSI: -44 dBm): 1A0684006C115D0000001100DA5045513139313837373440080000
06/03/19 19:29:17.596 Devlog (My-HM-CFG-LAN): Getting peer for packet 1A0684006C115D0000001100DA5045513139313837373440080000.
06/03/19 19:29:17.597 Devlog (My-HM-CFG-LAN): Packet 1A0684006C115D0000001100DA5045513139313837373440080000 is now passed to _receivedPackets.set.
06/03/19 19:29:17.597 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): -6C115D
06/03/19 19:29:17.598 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): +6C115D,00,00,
06/03/19 19:29:17.598 Module HomeMatic BidCoS: Debug: Setting physical device’s wake up flag.
06/03/19 19:29:17.598 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): -6C115D
06/03/19 19:29:17.598 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): +6C115D,02,00,
06/03/19 19:29:17.599 Module HomeMatic BidCoS: Info: Pushing pending queues.
06/03/19 19:29:17.599 Devlog (My-HM-CFG-LAN): _receivedPackets.set finished.
06/03/19 19:29:17.599 Debug (My-HM-CFG-LAN): Packet processing of packet 1A0684006C115D0000001100DA5045513139313837373440080000 took 3 ms.
06/03/19 19:29:17.655 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Sending (My-HM-CFG-LAN): 1025A00100AFFE6C115D00050000000000
06/03/19 19:29:17.655 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): S1E636057,00,00000000,01,FFFFFFFFC9B2AF3C,25A00100AFFE6C115D00050000000000
06/03/19 19:29:17.718 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): R1E636057,0002,00000000,FF,7FFF,F3
06/03/19 19:29:17.718 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Info: Ignoring too small packet: F3
06/03/19 19:29:20.602 Module HomeMatic BidCoS: Debug: Deleting queue 6 for BidCoS peer with address 0x6C115D
06/03/19 19:29:22.723 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Sending (encrypted): K
06/03/19 19:29:22.752 Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Debug: Packet received from HM-CFG-LAN (encrypted): HHM-LAN-IF,03C4,LEQ0384800,29A090,00AFFE,C9B2C322,000C,02

Vielleicht trägt das noch irgendwie zur Auflösung bei :slight_smile:

Hallo @rbott,

da stimmt irgendetwas nicht. Jedenfalls sind die empfangenen Antworten nicht die üblichen (z. B. R1E636057,0002,00000000,FF,7FFF,F3). Normalerweise sollte der letzte Teil (mit dem F3) mindestens 18 Zeichen lang sein. Hast du neben HomeMatic BidCoS noch andere 868-MHz-Geräte im Einsatz (außer MAX!)?

Viele Grüße

Sathya

Hallo @sathya,

Danke für Deine Antwort. Ich habe allerdings keine sonstigen Gerätefamilien an meiner homegear Instanz in Betrieb (und auch sonst nichts in meiner Wohnung). Ich bin mir relativ sicher, dass das Verhalten mit einem homegear Update vor einiger Zeit (ich kann leider kein genaues Datum mehr angeben) angefangen hat. Neue Geräte lassen sich derzeit auch nicht mehr anmelden. Ich könnte allerdings mal die komplette Datenbank wegschieben und mit einem “Clean State” anfangen und neue Geräte versuchen anzulernen. Aber nach dem was Du schreibst habe ich nicht das Gefühl, dass das etwas ändern würde :confused:

Grüße, Rudi