Kein Empfang nach "no answer to packet"-Meldung

max · October 13, 2016, 8:00pm

Hallo beisammen,

erst einmal vielen Dank für dieses tolle Programm und ein herzliches Hallo in die Homegear-Runde! Bislang konnte ich alle Konfigurationen erfolgreich selbst vornehmen, auch dank dieses tollen Forums, aber für mein aktuelle Problem habe ich leider keine Tipps in anderen Diskussionen finden können:

Kurz und knapp
Reproduzierbar startet Homegear erfolgreich, sendet und empfängt Pakete, bis im Log-File eine “no answer to packet”-Meldung auftaucht. Anschließend ist zwar Senden noch möglich, aber es werden keine Pakete mehr empfangen.

Nachfolgend versuche den Aufbau etwas genauer zu beschreiben und auch, was ich schon alles versucht habe um das Problem einzukreisen:

Ökosystem
Ich betreibe einen Raspi 3 mit HM-MOD-RPI-PCB Modul. Ich verwende die Homegear-Version 0.6.7-1480 auf einem aktuellen Raspbian Jessie. Als Front-End setze ich ioBroaker ein. Testweise habe ich folgende Komponenten mit Homegear gepaired: HM-Sys-sRP-Pl, HM-PB-4-WM, HM-Sen-MDIR-O, HM-Sen-MDIR-O-2, HM-Sen-MDIR-SM, HM-Sec-SC, HM-LC-Sw1-Ba-PCB.

Vor dem Fehler
Nach dem Start von Homegear verhält sich die Kommunikation wie erwartet. Auch das Log ist unauffällig …

10/13/16 19:04:40.581 Starting Homegear...
10/13/16 19:04:40.581 Homegear version 0.6.7-1480
10/13/16 19:04:40.581 Git commit SHA of libhomegear-base: -
10/13/16 19:04:40.581 Git branch of libhomegear-base:     -
10/13/16 19:04:40.582 Git commit SHA of Homegear:         -
10/13/16 19:04:40.582 Git branch of Homegear:             -
10/13/16 19:04:40.582 Info: Setting allowed core file size to "4294967295" for user with id 0 and group with id 0.
10/13/16 19:04:40.583 Info: Core file size now is "4294967295".
10/13/16 19:04:40.583 Info: Setting maximum thread priority to "4294967295" for user with id 0 and group with id 0.
10/13/16 19:04:40.583 Info: Maximum thread priority now is "4294967295".
10/13/16 19:04:40.592 Info: Backing up database...
10/13/16 19:04:40.673 Initializing database...
10/13/16 19:04:40.677 Info: Loading family module mod_miscellaneous.so
10/13/16 19:04:40.681 /etc/homegear/families/miscellaneous.conf
10/13/16 19:04:40.681 Info: Loading family module mod_homematicbidcos.so
10/13/16 19:04:40.693 /etc/homegear/families/homematicbidcos.conf
10/13/16 19:04:40.695 Info: Setting up physical interfaces and GPIOs...
10/13/16 19:04:40.696 Info: Dropping privileges to user homegear (112) and group homegear (119)
10/13/16 19:04:40.697 Info: Homegear is (now) running as user with id 112 and group with id 119.
10/13/16 19:04:40.697 Starting script engine server...
10/13/16 19:04:40.727 Initializing licensing controller...
10/13/16 19:04:40.727 Loading licensing controller data...
10/13/16 19:04:40.728 Loading devices...
10/13/16 19:04:40.728 Loading XML RPC devices...
10/13/16 19:04:41.729 Loading device 2
10/13/16 19:04:41.729 Module HomeMatic BidCoS: Info: Central address set to 0xXXXXXX.
10/13/16 19:04:41.730 Module HomeMatic BidCoS: Loading peer 1
10/13/16 19:04:41.746 Module HomeMatic BidCoS: Loading peer 2
10/13/16 19:04:41.751 Module HomeMatic BidCoS: Loading peer 3
10/13/16 19:04:41.755 Module HomeMatic BidCoS: Loading peer 4
10/13/16 19:04:41.760 Module HomeMatic BidCoS: Loading peer 5
10/13/16 19:04:41.763 Module HomeMatic BidCoS: Loading peer 6
10/13/16 19:04:41.766 Module HomeMatic BidCoS: Loading peer 7
10/13/16 19:04:41.772 Loading XML RPC devices...
10/13/16 19:04:41.774 Loading device 1
10/13/16 19:04:41.775 Start listening for packets...
10/13/16 19:04:43.776 Initializing RPC client...
10/13/16 19:04:43.776 Starting XML RPC server RPCServer1 listening on 0.0.0.0:2001...
10/13/16 19:04:43.779 Starting XML RPC server RPCServer2 listening on 0.0.0.0:2002, SSL enabled...
10/13/16 19:04:43.779 RPC Server (Port 2001): Info: RPC Server started listening on address 0.0.0.0 and port 2001
10/13/16 19:04:43.809 Starting XML RPC server RPCServer3 listening on 0.0.0.0:2003, SSL enabled, authentication enabled...
10/13/16 19:04:43.810 RPC Server (Port 2002): Info: RPC Server started listening on address 0.0.0.0 and port 2002
10/13/16 19:04:43.837 Starting CLI server...
10/13/16 19:04:43.837 RPC Server (Port 2003): Info: RPC Server started listening on address 0.0.0.0 and port 2003
10/13/16 19:04:43.837 Initializing event handler...
10/13/16 19:04:43.838 Loading events...
10/13/16 19:04:43.838 Startup complete. Waiting for physical interfaces to connect.
10/13/16 19:04:43.838 All physical interfaces are connected now.
10/13/16 19:04:43.838 Starting UPnP server...
10/13/16 19:04:43.839 Info: UPnP server: Binding to address: 192.168.X.X
10/13/16 19:04:43.840 UPnP Server: Info: Started listening.
10/13/16 19:04:46.231 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Init queue completed. Sending peers...
10/13/16 19:04:46.414 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Peer sending completed.
10/13/16 19:04:47.999 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -61 dBm): 0BB8A25818036418081C00FB
10/13/16 19:04:48.133 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -51 dBm): 0EB8820218081C1803640101C4003D
10/13/16 19:04:50.955 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -65 dBm): 1473845E2DD9D2000000870D43000000000008E8F8
10/13/16 19:04:57.244 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -60 dBm): 0B86A2581144011124BD0000
10/13/16 19:04:57.377 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -54 dBm): 0E8682021124BD1144010101000046
10/13/16 19:05:01.438 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -62 dBm): 0B0FA258166AB31672350000
10/13/16 19:05:01.580 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -70 dBm): 0CF48670160209000000008E3E
10/13/16 19:05:03.712 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -53 dBm): 0B96A25814331512479E0000
10/13/16 19:05:03.847 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -42 dBm): 0E96820212479E1433150101000031
10/13/16 19:05:07.836 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -59 dBm): 0CEF8670166A2800000000C037
10/13/16 19:05:21.581 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -70 dBm): 0BF4A2581602091653830000
10/13/16 19:05:21.717 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -65 dBm): 0EF48202165383160209010100062B
10/13/16 19:05:27.837 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -58 dBm): 0BEFA258166A28113C410021
10/13/16 19:05:27.969 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -39 dBm): 0EEF8202113C41166A2801011A002E
10/13/16 19:05:32.815 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -64 dBm): 0C4A867012B75300000000D13A
10/13/16 19:05:37.628 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0C5386701602C600000000D62F
10/13/16 19:05:47.936 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -69 dBm): 0C728670129BF400000000DE35
10/13/16 19:05:50.535 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0C0E86701669D200000000943C
10/13/16 19:05:52.815 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -63 dBm): 0B4AA25812B75313025C00CC
10/13/16 19:05:52.945 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0E4A820213025C12B7530101A0003E
10/13/16 19:05:55.306 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0C4686701144D200000000E92F
10/13/16 19:05:57.628 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0B53A2581602C61671630000
10/13/16 19:05:57.761 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -34 dBm): 0E5382021671631602C60101000035
10/13/16 19:06:02.625 RPC Server (Port 2001): Info: Connection from 127.0.0.1:46836 accepted. Client number: 11
10/13/16 19:06:02.626 RPC Server (Port 2001): Info: RPC server client id for client number 11 is: 0
10/13/16 19:06:02.627 RPC Server (Port 2001): Info: Client number 11 is calling RPC method: ping (1) Parameters:
(String) hm-rpc.0
10/13/16 19:06:02.652 RPC Server (Port 2001): Info: Connection to client number 11 closed (3).
10/13/16 19:06:07.936 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -69 dBm): 0B72A258129BF41808770079
10/13/16 19:06:08.072 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -54 dBm): 0E728202180877129BF401015E0035
10/13/16 19:06:10.535 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0B0EA2581669D216716D0000
10/13/16 19:06:10.667 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -51 dBm): 0E0E820216716D1669D20101000032
10/13/16 19:06:15.306 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -55 dBm): 0B46A2581144D2115043001F
10/13/16 19:06:15.438 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -56 dBm): 0E4682021150431144D20101180033
10/13/16 19:06:38.317 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -86 dBm): 0CE08670129CAB000000007E44
10/13/16 19:06:40.997 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -60 dBm): 0C878670114401000000008A3E
10/13/16 19:06:56.503 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -60 dBm): 0CB9867018036400000000A737
10/13/16 19:06:58.316 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -86 dBm): 0BE0A258129CAB1267A40000
10/13/16 19:06:58.449 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -60 dBm): 0EE082021267A4129CAB010100002F
10/13/16 19:07:00.997 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -60 dBm): 0B87A25811440112B1340000
10/13/16 19:07:01.132 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -56 dBm): 0E87820212B1341144010101000034
10/13/16 19:07:03.301 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -70 dBm): 0DC884101B8F9A13CD1A06011100
10/13/16 19:07:05.716 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -52 dBm): 0C97867014331500000000903D
10/13/16 19:07:13.342 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -70 dBm): 0D10A6103465D3FD901906010D00
10/13/16 19:07:13.343 Module HomeMatic BidCoS: Info: BRIGHTNESS on channel 1 of HomeMatic BidCoS peer 4 with serial number LEQ1282851 was set to 0x0D.

… bis zu der Zeile “… Info: No answer to packet …”.

ab hier ist kein Empfang mehr möglich

10/13/16 19:07:13.530 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 1011A001FD90193465D301040000000001
10/13/16 19:07:14.283 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: No answer to packet 1011A001FD90193465D301040000000001
10/13/16 19:08:02.649 RPC Server (Port 2001): Info: Connection from 127.0.0.1:46854 accepted. Client number: 12
10/13/16 19:08:02.650 RPC Server (Port 2001): Info: RPC server client id for client number 12 is: 1
10/13/16 19:08:02.650 RPC Server (Port 2001): Info: Client number 12 is calling RPC method: init (1) Parameters:
(String) http://127.0.0.1:2004
(String) hm-rpc.0
10/13/16 19:08:02.651 Info: Adding server "http://127.0.0.1".
10/13/16 19:08:02.651 Info: Calling init methods on server "http://127.0.0.1".
10/13/16 19:08:02.677 RPC Server (Port 2001): Info: Connection to client number 12 closed (3).
10/13/16 19:08:03.151 RPC client: Info: Calling RPC method "system.listMethods" on server http://127.0.0.1.
10/13/16 19:08:03.159 RPC client: Info: Calling RPC method "listDevices" on server 127.0.0.1.

Test: Senden während “fehlerhaftem” Empfang
Das weitere Log wiederholt sich nun ständig, bis ich einen Schaltbefehl veranlasse:

10/13/16 19:31:35.670 RPC Server (Port 2001): Info: Client number 45 is calling RPC method: setValue (1) Parameters:
(String) IEQ0405230:1
(String) STATE
(Boolean) 0
10/13/16 19:31:35.671 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 0E00B011FD00011769440201000000
10/13/16 19:31:38.254 RPC Server (Port 2001): Info: Connection to client number 45 closed (3).
10/13/16 19:31:39.212 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: No answer to packet 0E00B011FD00011769440201000000
10/13/16 19:31:39.212 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
10/13/16 19:31:39.213 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying...
10/13/16 19:31:39.213 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 0E00B011FD00011769440201000000
10/13/16 19:31:42.715 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: No answer to packet 0E00B011FD00011769440201000000
10/13/16 19:31:42.715 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
10/13/16 19:31:42.716 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying...
10/13/16 19:31:42.716 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 0E00B011FD00011769440201000000
10/13/16 19:31:46.214 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: No answer to packet 0E00B011FD00011769440201000000
10/13/16 19:31:46.215 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
10/13/16 19:31:46.215 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying...
10/13/16 19:31:46.215 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 0E00B011FD00011769440201000000
10/13/16 19:31:49.717 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: No answer to packet 0E00B011FD00011769440201000000
10/13/16 19:31:49.718 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
10/13/16 19:31:49.718 Info: Peer 7 is unreachable.
10/13/16 19:31:49.718 RPC client: Info: Calling RPC method "system.multicall" on server 127.0.0.1.
10/13/16 19:31:50.183 Info: Peer 7 is reachable again.
10/13/16 19:31:50.183 RPC client: Info: Calling RPC method "system.multicall" on server 127.0.0.1.

Der Befehl erreicht den Aktor und wird umgehend ausgeführt, die Bestätigung wird aber nicht mehr (oder zu spät?) empfangen.

Test: Senden gleich nach dem Start von Homegear
Führe ich denselben Schaltbefehl gleich nach einem Neustart von Homegear aus, funktioniert es prima und wird folgendermaßen protokolliert:

10/13/16 19:36:11.968 RPC Server (Port 2001): Info: RPC server client id for client number 11 is: 0
10/13/16 19:36:11.971 RPC Server (Port 2001): Info: Client number 11 is calling RPC method: setValue (1) Parameters:
(String) IEQ0405230:1
(String) STATE
(Boolean) 0
10/13/16 19:36:11.972 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Info: Sending (My-HM-MOD-RPI-PCB): 0E02B011FD00011769440201000000
10/13/16 19:36:12.485 HomeMatic BidCoS packet received (My-HM-MOD-RPI-PCB, RSSI: -62 dBm): 0E028002176944FD00010101000042
10/13/16 19:36:12.485 Module HomeMatic BidCoS: Info: Queue 0 is empty and there are no pending queues.
10/13/16 19:36:12.486 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 7 with serial number IEQ040XXXX was set to 0x00.
10/13/16 19:36:12.486 Module HomeMatic BidCoS: Info: WORKING on channel 1 of HomeMatic BidCoS peer 7 with serial number IEQ040XXXX was set to 0x00.
10/13/16 19:36:12.548 RPC Server (Port 2001): Info: Connection to client number 11 closed (3).

Ich bin leider mit meinem Latein am Ende. Habt ihr eine Idee, woran das liegen könnte?

Viele Grüße,
max

max · October 17, 2016, 7:11pm

Hat keiner eine Idee, woran das liegen könnte? Oder einen Tipp, was ich noch untersuchen sollte?

Ein apt-get remove und install hat leider auch nichts verändert…

Grüße,
max

sathya · October 17, 2016, 8:52pm

Hallo @max,

komisch… Könntest du mir per E-Mail das gesamte Log schicken? Ich werde morgen einmal bei mir etwas rumspielen und schaue mal, ob ich das Problem reproduziert bekomme.

Viele Grüße

Sathya

sathya · October 18, 2016, 12:30pm

Hallo @max,

auf die schnelle bekomme ich das Problem auf deinem Raspberry Pi 2 nicht reproduziert. Ich teste aber noch etwas weiter und werde auch über Nacht einen Test laufen lassen. Nach welcher Zeit tritt das Problem bei dir auf? Nutzt du das offizielle Homegear-Image oder Raspbian? Ich habe mal das Debugging etwas verbessert. Könntest du das aktuelle Nightly installieren und Homegear bis zum Fehler mit Debuglevel 5 laufen lassen? Das vollständige Log kannst du mir dann per E-Mail schicken. Vielleicht gibt das etwas Aufschluss darüber, was schief läuft.

Viele Grüße

Sathya

max · October 18, 2016, 1:31pm

Hi Sathya,

vielen Dank schon mal für deine Antwort. Ich verwende das aktuelle Raspbian Image samt Updates und habe parallel noch ioBroker installiert. Nach ca. 2min. und ca. 30 empfangenen Meldungen ist es vorbei. Ich melde mich, sobald ich wieder zuhause bin und testen kann!

Grüße,
max

sathya · October 19, 2016, 3:07pm

Hallo @max,

danke für das Log. Ich sehe da keinerlei Auffälligkeiten. Inzwischen habe ich bei mir auch einen Pi 3 mit dem Modul zum Laufen gebracht. Was mir in dem Zusammenhang aufgefallen ist: Die Serial-Konsole wurde auf “/dev/ttyS0” gelegt. Das heißt, in der Doku fehlten noch einige Punkte:

systemctl disable serial-getty@serial0.service
systemctl disable serial-getty@ttyS0.service

Außerdem muss aus der “/boot/cmdline.txt” /dev/ttyS0 und /dev/serial0 entfernt werden.

Mein Pi 3 schaltet einen HomeMatic-Schaltaktor inzwischen seit fast drei Stunden alle drei Sekunden. Ist bei dir vielleicht einer der oben genannten Gründe die Ursache? Falls nein, bin ich etwas ratlos. Mit Debuglevel 5 werden alle roh über die serielle Schnittstelle eingehenden Daten angezeigt. Aus irgendeinem Grund kommt bei dir einfach nichts. Hättest du vielleicht noch einen Pi 2 zur Verfügung, um es damit zu testen?

Viele Grüße

Sathya

max · November 5, 2016, 6:33pm

Hi sathya,

die Schritte hatte ich durchgeführt. Es hat auch schon mal mehrere Tage funktioniert, ehe ich die Komponenten testweise mehrmals ab und wieder angelernt habe. Das Backup von damals läuft auch noch, wobei da nur eine Komponente angelernt ist.
Ich habe noch einen Zero, auf dem ich leider keine Verbindung zum Funkmodul bekomme (permission denied auf GPIO 18).
Irgendwo muss ein Fehler beim Hinzufügen und Löschen der Komponenten passiert sein. Das Problem ist also noch nicht gelöst, ich kann es aber umgehen, indem ich das alte Backup verwende. Wenn du willst, kann ich dir gerne noch mehr Daten liefern.

Vielen Dank für deine Unterstützung!

Grüße,
max

sathya · November 8, 2016, 10:43pm

Das ist ja wirklich merkwürdig. Kannst du mir einmal deine komplette Konfiguration aus “/etc/homegear” und die nicht funktionierende Datenbank ("/var/lib/homegear/db.sql") schicken? Dann schaue ich mal, ob ich damit das Problem reproduziert bekomme.

Viele Grüße

Sathya

max · November 21, 2016, 8:04am

Hi Sathya,

ich habe das mal mit meinen Möglichkeiten etwas weiter untersucht und kann das Verhalten ungefähr reproduzieren. Wenn ich nach einer frischen Installation Geräte wie einen HM-CC-TC mehrmals an- und wieder ablerne verschluckt sich homegear scheinbar. Im HM-Manager bleibt das Gerät bestehen, obwohl es in Homegear weg ist. Wenn es dann mit dieser Homegear-Instanz nicht mehr gekoppelt werden kann, habe ich geprüft, ob es an dem HM-Gerät liegt. Und siehe da, ich kann es problemlos mit einer identischen frischen Installation auf einem zweiten Raspi verbinden.

Ich habe dir gerade ein komplettes Backup zugeschickt. Wo könnte ich noch den Fehler suchen?

Grüße,
Max

sathya · January 13, 2017, 4:00pm

Hallo @max,

habe gerade den HM-MOD-RPI-PCB noch einmal ausgiebig getestet und ein HM-CC-TC insgesamt 10 Mal angelernt und wieder resettet. Nach wievielen Versuchen tritt das Problem bei dir auf? Hat dein HM-MOD-RPI-PCB die aktuelle Firmware (siehe https://wiki.fhem.de/wiki/HM-MOD-RPI-PCB_HomeMatic_Funkmodul_für_Raspberry_Pi#Alternative_Methode_zum_Firmware_Update_ohne_FHEM)?

Viele Grüße

Sathya

max · January 29, 2017, 12:32pm

Hi Sathya,

Auf die Idee, dass das Funkmodul ein Update braucht um richtig zu funktionieren, wäre ich selbst nie gekommen. Ich habe jetzt mal meine beiden Module geflasht und siehe da, es funktioniert besser, da es bislang zu keinem Totalausfall mehr gekommen ist. Ab und zu hängt das Modul noch und reagiert für Minuten nicht auf Meldungen von HM-Sec-XXX Geräten. Dem werde ich in den nächsten Tagen nachgehen und schauen, ob erneutes Anlernen vielleicht Abhilfe schafft.

Aber nochmal zur Firmware:
Neben der im FHEM-verlinkten habe ich noch zwei (ältere?) Versionen unter https://git.zerfleddert.de/hmcfgusb/firmware/ gefunden.
Grundsätzlich würden mich folgende Punkte sehr interessieren:

Wo werden neue Firmware-Versionen veröffentlicht?
Wie kann man ohne FHEM die aktuell geflashte Version herausfinden?

Hier noch mein erfolgreiches Vorgehen zum Flashen der Firmware:

# 1. root
sudo su
# 2. homegear beenden
systemctl stop homegear
# 3. autostart deaktiveren
update-rc.d homegear disable
# 4. System herunterfahren
halt
# 5. Modul stromlos machen und anschließend wieder einschalten

# 6. Entsprechend [FHEM Wiki](https://wiki.fhem.de/wiki/HM-MOD-RPI-PCB_HomeMatic_Funkmodul_f%C3%BCr_Raspberry_Pi#Alternative_Methode_zum_Firmware_Update_ohne_FHEM) vorgehen:
sudo su
apt-get update && apt-get -y install libusb-1.0-0-dev build-essential git
systemctl stop fhem
git clone git://git.zerfleddert.de/hmcfgusb
cd hmcfgusb/
make
# Firmware runterladen
wget https://raw.githubusercontent.com/eq-3/occu/ee68faf77e42ed5e3641790b43a710a3301cea7e/firmware/HM-MOD-UART/coprocessor_update.eq3
# eigentliches flashen:
./flash-hmmoduart -U /dev/ttyAMA0 coprocessor_update.eq3

# 7. Flashvorgang verifizieren
????
# 8. autostart wieder aktivieren
update-rc.d homegear enable
# 9. homegear wieder starten
systemctl start homegear

Viele Grüße,
Max

sathya · January 14, 2017, 8:06pm

Hi @max,

Im OCCU-Projekt: https://github.com/eq-3/occu/tree/master/firmware/HM-MOD-UART

Baue ich die Tage in Homegear ein. Dann wird die Version im Log angezeigt.

Viele Grüße

Sathya

Nachtrag: Das Deaktivieren von Homegear, der Neustart und das Stromlosmachen des Moduls sind nicht notwendig für die Firmwareaktualisierung.

max · January 29, 2017, 10:37am

Hi @sathya ,

Danke!

/*Nachtrag: Das Flashen hat bei mir mit der beschriebenen Methode einwandfrei funktioniert. Ich musste das Modul aber unbedingt stromlos machen und den Start von Homegear unterbinden. Ansonsten wäre folgendes aufgetreten: */
Bei meinen Flash-Versuchen wurde zwar die Quelldatei eingelesen, jedoch nicht geflasht. Sollte ein “sudo systemctl stop homegear.service” reichen oder muss ich das Modul noch irgendwie zurücksetzen?

@topic
Ich komme mit meinen Tests voran. Die Sende-/Empfangsqualität scheint einen entscheidenden Einfluss auf das anfangs beobachtete Verhalten zu haben. Ich habe zu Testzwecken die Sendeleistung (elektrisch) reduziert und das System eine Weile laufen lassen. Zwar empfangen und übermitteln alle Geräte am Anfang noch Daten, aber nach und nach gehen sie in Störung. Im Homegear-Log finde ich dann ziemlich genau alle 10s die Meldung “Error: No response received to packet:…”. Nun ist keine Kommunikation mehr möglich. Ein service.restart oder reboot wird erst nach mehr als einer Minute ausgeführt, während homegear noch fleißig das log befüllt. Es wirkt, als würde eine gewisse Menge an fehlerhaft übermittelten Paketen zu einer kompletten Blockierung führen. Ein service.restart reicht um die Kommunikation wieder aufzunehmen. Ich schätze, es gibt eine Art Warteschlange für Pakete. Lässt sich diese irgendwie anzeigen und zurücksetzen?

Auszug aus Log1:

01/16/17 16:30:29.406 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019102000000D2A25839025C13025C0048836E
01/16/17 16:30:39.407 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A102000000D2A25839025C13025C004823CE
01/16/17 16:30:49.408 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A202000000D2A25839025C13025C004829C4
01/16/17 16:30:59.410 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019402000000D1A2583908771808770005E8C1
01/16/17 16:31:09.411 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A402000000D1A25839087718087700054861
01/16/17 16:31:19.412 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A502000000D1A2583908771808770005CE64
01/16/17 16:31:29.414 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101970200000094A258397163167163020017D8
01/16/17 16:31:39.415 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A70200000094A2583971631671630200B778
01/16/17 16:31:49.417 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A80200000094A2583971631671630200955A
01/16/17 16:31:59.418 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019A02000000DAA25839723516723502009733
01/16/17 16:32:09.420 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101AA02000000DAA25839723516723502003793

Auszug aus Log 2:

01/16/17 16:33:21.143 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011018F020000002FA2583924BD1124BD02008514
01/16/17 16:33:31.144 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019F020000002FA2583924BD1124BD02006577
01/16/17 16:33:41.145 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A0020000002FA2583924BD1124BD0200E7F5
01/16/17 16:33:51.147 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD00110192020000002AA25839538316538302008531
01/16/17 16:34:01.149 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A2020000002AA25839538316538302002591
01/16/17 16:34:11.150 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A3020000002AA2583953831653830200A394
01/16/17 16:34:21.152 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101950200000031A2583967A41267A402009A87
01/16/17 16:34:31.154 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A50200000031A2583967A41267A402003A27
01/16/17 16:34:41.156 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A60200000031A2583967A41267A40200302D
01/16/17 16:34:51.157 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101980200000001A25839716D16716D02005154
01/16/17 16:35:01.159 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A80200000001A25839716D16716D0200F1F4
01/16/17 16:35:11.160 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101A90200000001A25839716D16716D020077F1
01/16/17 16:35:21.162 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019B0200000030A25839B13412B1340200C37D
01/16/17 16:35:31.164 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101AB0200000030A25839B13412B134020063DD
01/16/17 16:35:41.165 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101AC0200000030A25839B13412B1340200F1CC
01/16/17 16:35:51.167 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD0011019E02000000B0A25839504311504300176446
01/16/17 16:36:01.169 Module HomeMatic BidCoS: HM-MOD-RPI-PCB "My-HM-MOD-RPI-PCB": Error: No response received to packet: FD001101AE02000000B0A2583950431150430017C4E6

BTW: was bedeutet eigentlich das FD0011 am Anfang?

Grüße,
Max

sathya · January 16, 2017, 4:49pm

Hey @max,

wow, danke für die Analyse. Das hilft ein ganzes Stück weiter. 10 Sekunden ist das Timeout bis zum Erhalt einer Antwort. Bekommst du das Ganze noch einmal auf Debuglevel 5 reproduziert? Dann werden nämlich die Rohpakete geloggt. Die homegear.log sollte dann sämtliche Informationen enthalten, welche ich zum Lösen des Problems benötige.

FD ist das Startbyte und 0011 die Länge des Pakets.

Viele Grüße

Sathya

Chris81T · January 29, 2017, 9:11am

Hallo zusammen,

ich selbst habe hier auch einen Raspberry Pi 3 mit dem Homematic Bausatz im Betrieb. Bisher hatte ich mit einer eigenen Java Implementierung + Process Engine Camunda + HMCon die Sache in Verwendung. Da jetzt aber OpenHAB in 2.0 draußen ist, wollte ich mir das einmal genauer anschauen.

Dort fand ich, dass Homegear zum Ansprechen ebenfalls benötigt wird. Ich bin nach der Homegear Wiki Anleitung vorgegangen, hatte es aber nicht geschafft, erfolgreich ein “pairing” durchzuführen. Auch ich fand im Log “Module HomeMatic BidCoS: HM-MOD-RPI-PCB “My-HM-MOD-RPI-PCB”: Error: No init packet received.”.

Daher bin ich auf diesen Post hier gestoßen. Mittlerweile habe ich es mit Homegear in Betrieb. DIe Lösung war das zurücksetzen meiner Rollladenaktoren in den Werkszustand (habe aktuell nur 2 von den Aktoren - wird noch ausgebaut ). Danach konnte ich das pairing erfolgreich durchführen und wurde in OpenHAB2 direkt erkannt. Prima

Interessant fand ich hier die Info mit der Firmware:

Da es mit dem Pairing zu Beginn nicht klappte, wollte ich auch die Firmware aktualisieren, aber habe genau das gleiche Problem: Die Quell-Datei wird gelesen, aber nicht geflasht.

@sathya: Kannst du vielleicht kurz beschreiben, wie du es bei dir flashst? Das wäre super!

Ansonsten: “Good Job” mit Homegear!

Viele Grüße
Christian

max · January 29, 2017, 9:47am

Hi @Chris81T,

weiter oben habe ich mein Vorgehen zum Flashen genau beschrieben. Das sollte eigentlich funktionieren. Bist du alle Schritte genau durchgegangen?

Grüße,
max

Chris81T · January 29, 2017, 9:21pm

Hi @max,

danke für deinen Hinweis. Mir ist dein “Nachtrag” nicht aufgefallen und hab somit überlesen, dass du explizit die Platine stromlos gesetzt hast, damit es funktioniert.

Muss ich dann noch ausprobieren. Aber eine Frage dazu: Wie lange hast du den Pi vom Netz genommen. Raus und rein, oder bewusst ein paar Minuten gewartet?

max · January 30, 2017, 4:30pm

Hi @Chris81T,

den Nachtrag habe ich nach deiner Nachricht hinzugefügt um es nochmal deutlicher zu machen. Ich habe das Modul kurz, also ein paar Sekunden, vom Netz genommen.

Grüße,
Max

meta0102 · May 8, 2018, 5:31am

perfekt, bei mir hatte auch das Firmware Update gefehlt!
danke, jetzt funktioniert alles!