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

Herr.Vorragend · February 3, 2019, 10:08am

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!

jensk · February 3, 2019, 5:20pm

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

job · February 3, 2019, 5:27pm

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.

jensk · February 3, 2019, 7:20pm

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.

HomeSweetHome · February 3, 2019, 8:08pm

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”)…

rocla · February 7, 2019, 8:09pm

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

Herr.Vorragend · February 8, 2019, 6:05am

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.

Herr.Vorragend · February 14, 2019, 6:33pm

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

sathya · February 22, 2019, 5:03pm

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

rocla · March 3, 2019, 12:22pm

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

sathya · March 8, 2019, 3:41pm

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

empi89 · March 9, 2019, 3:10pm

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

dklueh · March 9, 2019, 4:50pm

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

job · March 10, 2019, 11:32am

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

Herr.Vorragend · March 17, 2019, 3:50pm

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.

Mitch77 · March 18, 2019, 8:21pm

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

dklueh · March 18, 2019, 8:44pm

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|

dklueh · March 18, 2019, 9:01pm

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

mburgholte · March 29, 2019, 10:50am

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

Malspherus · April 28, 2019, 4:12pm

Hallo, habe momentan immer wieder das selbe Problem auf Version 0.7.39-2768

04/28/19 17:44:37.837 RPC Server (Port 2001): Info: Client number 58 is calling RPC method: setValue (2) Parameters:
(String) OEQ047****:1
(String) STATE
(Boolean) 0
04/28/19 17:44:40.870 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
04/28/19 17:44:40.871 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 6). Retrying...

Welche Felder soll ich dir in der Config setzen @sathya? Reicht debugLevel = 5 und devLog = true?

Ich habe meines Wissens nach kein Update oder sonstige Veränderungen gemacht, von einem Tag auf den anderen hats nicht mehr verlässlich funktioniert.