Homegear/OpenHAB 2.4 “No answer from device. (sending setValue()”


#1

Hallo zusammen,

ich habe OpenHAB 2.4 mit openHABian auf einem Raspberry Pi 3 Model B laufen. Um meine Homematic-Komponenten mit dem HM-MOD-RPI-PCB Module zu nutzen ist homegear via openHABian installiert. Bis gestern lief alles ohne Probleme, dann wurde ein Update von homegear installiert und seitdem kann ich meine Homematic-Geräte nicht mehr nutzen!
OpenHAB zeigt alle Geräte als “online” an, wenn ich sie nutzen will erscheint im Log folgendes:

2019-02-03 10:55:05.873 [vent.ItemStateChangedEvent] - EG_Kueche_Rolladen_Vorne_Level changed from 0 to 100

2019-02-03 10:55:16.846 [ome.event.ItemCommandEvent] - Item 'EG_Kueche_Rolladen_Vorne_Level' received command DOWN

2019-02-03 10:55:16.860 [nt.ItemStatePredictedEvent] - EG_Kueche_Rolladen_Vorne_Level predicted to become DOWN

==> /var/log/openhab2/openhab.log <==

2019-02-03 10:55:21.991 [ERROR] [ematic.handler.HomematicThingHandler] - -100 No answer from device. (sending setValue()

OEQ0982801:1

LEVEL

0.0

)

java.io.IOException: -100 No answer from device. (sending setValue()

OEQ0982801:1

LEVEL

0.0

)

	at org.eclipse.smarthome.binding.homematic.internal.communicator.parser.RpcResponseParser.parse(RpcResponseParser.java:51) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:82) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:94) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:70) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.client.RpcClient.setDatapointValue(RpcClient.java:335) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.AbstractHomematicGateway$1.execute(AbstractHomematicGateway.java:686) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.misc.DelayedExecuter.start(DelayedExecuter.java:65) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.AbstractHomematicGateway.sendDatapoint(AbstractHomematicGateway.java:665) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.internal.communicator.AbstractHomematicGateway.sendDatapoint(AbstractHomematicGateway.java:644) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.handler.HomematicThingHandler.sendDatapoint(HomematicThingHandler.java:307) ~[201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.binding.homematic.handler.HomematicThingHandler.handleCommand(HomematicThingHandler.java:283) [201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [102:org.eclipse.smarthome.core:0.10.0.oh240]

	at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [102:org.eclipse.smarthome.core:0.10.0.oh240]

	at com.sun.proxy.$Proxy121.handleCommand(Unknown Source) [201:org.eclipse.smarthome.binding.homematic:0.10.0.oh240]

	at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:75) [109:org.eclipse.smarthome.core.thing:0.10.0.oh240]

	at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [109:org.eclipse.smarthome.core.thing:0.10.0.oh240]

	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [102:org.eclipse.smarthome.core:0.10.0.oh240]

	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [102:org.eclipse.smarthome.core:0.10.0.oh240]

	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]

	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]

	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]

	at java.lang.Thread.run(Thread.java:748) [?:?]

Das homegear-Log gibt folgende Meldung aus:
02/03/19 10:55:16.862 RPC Server (Port 2001): Info: Client number 41 is calling RPC method: setValue (2) Parameters:
(String) OEQ0982801:1
(String) LEVEL
(Float) 0
02/03/19 10:55:16.863 Module HomeMatic BidCoS: HM-MOD-RPI-PCB “My-HM-MOD-RPI-PCB”: Warning: !!!Not!!! sending packet, because init sequence is not complete: 0BFBA0110FD5C46074B40301
02/03/19 10:55:19.428 RPC Server (Port 2001): Info: Connection from ::ffff:192.168.178.123:38526 accepted. Client number: 44
02/03/19 10:55:19.428 RPC Server (Port 2001): Info: RPC server client id for client number 44 is: 24
02/03/19 10:55:19.430 RPC Server (Port 2001): Info: Client number 44 is calling RPC method: setValue (2) Parameters:
(String) OEQ0982801:1
(String) LEVEL
(Float) 0
02/03/19 10:55:21.991 RPC Server (Port 2001): Info: Client number 44 is calling RPC method: ping (2) Parameters:
(String) 5370de0b
02/03/19 10:55:21.994 RPC Server (Port 2001): Info: Client number 44 is calling RPC method: listBidcosInterfaces (2) Parameters:
02/03/19 10:55:22.477 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
02/03/19 10:55:22.477 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying…
02/03/19 10:55:22.577 Module HomeMatic BidCoS: HM-MOD-RPI-PCB “My-HM-MOD-RPI-PCB”: Warning: !!!Not!!! sending packet, because init sequence is not complete: 0BFBA0110FD5C46074B40301
02/03/19 10:55:25.582 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
02/03/19 10:55:25.582 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying…
02/03/19 10:55:25.683 Module HomeMatic BidCoS: HM-MOD-RPI-PCB “My-HM-MOD-RPI-PCB”: Warning: !!!Not!!! sending packet, because init sequence is not complete: 0BFBA0110FD5C46074B40301
02/03/19 10:55:28.687 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
02/03/19 10:55:28.687 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 7). Retrying…
02/03/19 10:55:28.788 Module HomeMatic BidCoS: HM-MOD-RPI-PCB “My-HM-MOD-RPI-PCB”: Warning: !!!Not!!! sending packet, because init sequence is not complete: 0BFBA0110FD5C46074B40301
02/03/19 10:55:31.793 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
02/03/19 10:55:31.793 Info: Peer 7 is unreachable.
02/03/19 10:55:31.855 Info: Peer 7 is reachable again.

Ich habe keine Ahnung, was tun und bin für jeden Hinweis dankbar!


#2

Falls es Dich beruhigt. Ich habe das gleiche Problem hier.
apt upgrade auf Openhabian 2.4 (auf homegear 0.7.34-2678) und nun kann ich nicht mehr Befehle senden. Empfangen geht. Senden nicht.

[ERROR] [ematic.handler.HomematicThingHandler] - -100 No answer from device. (sending setValue()

Grüße,
Jens


#3

Ich kann zwar zu eurem Problem nichts beitragen, allerdings funktioniert bei mir Homegear 0.8.0-2514 problemlos mit openHAB 2.4.

Ich denke nicht, dass im Homegear stable Änderungen sind, die nicht im nightly sind. Von daher denke ich, dass eure Probleme an anderer Stelle liegen.


#4

kurzer Nachtrag: Ein Downgrade auf 0.7.30-1900 löst das Problem:

apt-get install homegear=0.7.30-1900 libhomegear-base=0.7.30-1900 homegear-homematicbidcos=0.7.30-1900

Obacht: Nach dem Downgrade muss die DB in /var/lib/homegear/ zurückgespielt werden.

BG.


#5

Keine Ahnung, ob das weiterhilft: habe openHAB 2.4 mit homegear 0.7.34-2678 ohne Fehler am Laufen, ABER ich nutze noch das “homematic1”-binding (ohne “Things”)…


#6

Nach dem Update/Upgrade über openhabian habe ich diesen Fehler auch gehabt. Schaut euch die Update Log- Datei an. Hier habe ich folgende Warnung bekommen.
Warning: homegear.service changed on disk. Run ‘systemctl daemon-reload’ to reload units.
Nach dem Update dann
sudo systemctl daemon-reload units
und anschliessend reboot und alles funktioniert wieder.
VG Rocla


Homematic peers unreachable - processing interrupted
#7

Super, vielen Dank für eure Antworten!
Ich habe jetzt gerade ein - zum Glück kurz zuvor erstelltes - Backup laufen und auf das Update verzichtet.
Beim nächsten Update werde ich dann aber den Tipp von @rocla probieren.


#8

Heute habe ich mich mal getraut, das Update anzustoßen und dabei homegear auf die stable version 0.7.36-2698 aktualisiert.
Leider nach wie vor das selbe Verhalten:
[ERROR] [ematic.handler.HomematicThingHandler] - -100 No answer from device. (sending setValue()

Auch der Tipp von @rocla

brachte leider keine Besserung. Werde also das letzte Backup (in weiser Voraussicht vor dem Updaten erstellt) wieder einspielen und auf eine Lösung hoffen…

Beste Grüße, Bond


#9

Hallo,

[ERROR] [ematic.handler.HomematicThingHandler] - -100 No answer from device. (sending setValue()

hätte jemand beim nächsten Versuch ein homegear.log vom Homegearstart auf Loglevel 5 für mich? Das hat vermutlich nichts mit OpenHAB zu tun, sondern mit der Kommunikation zum HM-MOD-RPI-PCB. Da klappt irgendetwas nicht.

Viele Grüße

Sathya


#10

Hallo Sathya,
seit gestern habe ich auch wieder diesen Fehler.
Ich habe dir die homegear.log geschickt.
Ich hoffe das du da einen Fehler finden kannst.
Viele Grüße
Rocla


#11

Hallo,

nur zur Info: Das Problem bei @rocla lag wohl an einem defekten Netzwerkkabel… Falls also jemand anders ein Log vom Homegearstart auf Level 5 für mich hätte…

Viele Grüße

Sathya


#12

Hallo,

hatte auf diesen Versionen:
ii homegear 0.7.37-2722 armhf Interface program to your smart home devices
ii homegear-homematicbidcos 0.7.37-2722 armhf HomeMatic BidCoS module for Homegear
ii libhomegear-base 0.7.37-2722 armhf Base library for Homegear
ii libhomegear-ipc 0.1.2-21 armhf IPC library for Homegear
ii libhomegear-node 0.1.7-35 armhf Node library for Homegear

Auch das hier beschriebene Problem. Nach Downgrade mit
apt-get install homegear=0.7.30-1900 libhomegear-base=0.7.30-1900 homegear-homematicbidcos=0.7.30-1900
hat es dann wieder funktioniert.

Nutze einen HM-CFG-LAN.

Viele Grüße
Peter


#13

Geht das mit ner DB, die schon einen höheren Stand hat?
Bei mir meckert er nach nem Downgrade über eine falsche DB Version…


#14

Warum schickt ihr @sathya nicht erstmal ein log auf Level 5, damit er das Problem lösen kann?


#15

Mir fehlt leider im Moment die Zeit und ich bin froh, dass mein System stabil läuft - sobald möglich, werde ich jedoch updaten und dann das entsprechende Log zur Verfügung stellen.


#16

Hi,
hast du dafür schon eine Lösung gefunden? Hab’ das gleiche Problem.
Viele Grüße,
Mitch


#17

Ich hatte nen Downgrade auf Version: 0.7.30-1900 gemacht - alles gut soweit.

Bis heute Nachmittag…seitdem lassen sich alle Homematic Thermostate wieder nicht mehr steuern…

Fehler im IObroker:

|hm-rpc.0|2019-03-18 21:40:06.737|error|Error: XML-RPC fault: Device not found.|
|—|---|—|---|
|hm-rpc.0|2019-03-18 21:40:06.736|error|xmlrpc -> setValue [“4”,“AUTO_MODE”,false] ACTION|


#18

Ich habe gerade gesehen, dass homegear offen ebar alle devices verloren hat…auch die Sicherung funzt nicht mehr…:disappointed_relieved:


#20

Von meiner Seite aus Entwarnung. Mit dem letzten Nightly und dem “korrekten” Herstellen des Backups (sorry) funktioniert nun alles fehlerfrei.