Datenupdates von homegear zu openhab2

Deutsche Foren openHAB
#1

Hallo Leute,

vielleicht sehe ich den Wald vor lauter Bäumen nicht, aber ich bekomme es nicht gebacken, daß openhab2 Daten/Updates aus homegear bekommt/anzeigt.

Ich habe einen raspberry mit openhabian inklusive homegear aufgesetzt, alles läuft soweit und homegear holt regelmäßig die Daten von den 5 Thermostaten. Zumindest interpretiere ich das so:

[...]
    11/15/18 21:44:35.196 Module HomeMatic BidCoS: Info: ACTUAL_TEMPERATURE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x00E1.
    11/15/18 21:44:35.196 Module HomeMatic BidCoS: Info: BATTERY_STATE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x10.
    11/15/18 21:44:35.197 Module HomeMatic BidCoS: Info: BOOST_STATE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x00.
    11/15/18 21:44:35.197 Module HomeMatic BidCoS: Info: CONTROL_MODE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x00.
    11/15/18 21:44:35.197 MQTT Client Info: Publishing topic   homegear/1234-5678-9abc/plain/13/0/LOWBAT
    11/15/18 21:44:35.197 Module HomeMatic BidCoS: Info: FAULT_REPORTING on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x00.
    11/15/18 21:44:35.197 Module HomeMatic BidCoS: Info: PARTY_START_TIME on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x00.
    11/15/18 21:44:35.197 Module HomeMatic BidCoS: Info: SET_TEMPERATURE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x2A.
    11/15/18 21:44:35.198 Module HomeMatic BidCoS: Info: VALVE_STATE on channel 4 of HomeMatic BidCoS peer 13 with serial number OEQ1715434 was set to 0x0D.
[...]

openhab holt sich auch initial die Daten soweit ich das sehe:

[...]
    2018-11-07 18:14:59.441 [vent.ItemStateChangedEvent] - ThermBad_0_LowBattery changed from NULL to OFF
    2018-11-07 18:14:59.450 [vent.ItemStateChangedEvent] - ThermBad_3_WindowState changed from NULL to OFF
    2018-11-07 18:14:59.542 [vent.ItemStateChangedEvent] - ThermBad_4_SetTemperature changed from NULL to 21.0 ℃
    2018-11-07 18:14:59.546 [vent.ItemStateChangedEvent] - ThermBad_4_ActualTemperature changed from NULL to 21.1 ℃
    2018-11-07 18:14:59.548 [vent.ItemStateChangedEvent] - ThermBad_4_ValveState changed from NULL to 0
    2018-11-07 18:14:59.596 [me.event.ThingUpdatedEvent] - Thing 'homematic:HG-HM-CC-RT-DN:e83078fc:OEQ2085078' has been updated.
[...]

Es werden dann in der PaperUI auch Werte angezeigt, auch in Grafana werden via influxdb Werte angezeigt, aber diese ändern sich vielleicht noch die ersten 5 Minuten, und dann bleiben sie stehen, über Stunden. Erst wenn ich homegear oder openhab2 neu starte werden wieder frische Daten eingelesen.

Was könnte die Ursache sein, bzw. wo genau muß ich suchen?

Gruss vom Matze

#2

Hallo,

habe noch etwas weiter beobachtet und folgendes im homegear.log gefunden, was nicht richtig ausschaut:

[...]
11/17/18 11:06:26.360 RPC Server (Port 2001): Info: Connection from ::ffff:127.0.0.1:43702 accepted. Client number: 10133
11/17/18 11:06:26.361 RPC Server (Port 2001): Info: RPC server client id for client number 10133 is: 88
11/17/18 11:06:26.362 RPC Server (Port 2001): Info: Client number 10133 is calling RPC method: ping (2) Parameters:
(String) 5fe5fa93
11/17/18 11:06:26.363 MQTT Client Info: Publishing topic   homegear/1234-5678-9abc/json/0/-1/PONG
11/17/18 11:06:26.364 MQTT Client Info: Publishing topic   homegear/1234-5678-9abc/plain/0/-1/PONG
11/17/18 11:06:26.366 MQTT Client Info: Publishing topic   homegear/1234-5678-9abc/jsonobj/0/-1
11/17/18 11:06:26.997 RPC Server (Port 2001): Info: Connection from ::ffff:192168.178.20:52820 accepted. Client number: 10135
11/17/18 11:06:26.998 RPC Server (Port 2001): Info: RPC server client id for client number 10135 is: 89
11/17/18 11:06:27.000 RPC Server (Port 2001): Info: Client number 10135 is calling RPC method: init (1) Parameters:
(String) http://192.168.178.20:2000
(String) hmm_BidCos-RF
11/17/18 11:06:27.000 Info: Client with IP ::ffff:192.168.178.20 is calling "init".
11/17/18 11:06:27.000 Info: Removing server "http://192.168.178.20".
11/17/18 11:06:27.000 Info: Adding server "http://192.168.178.20".
11/17/18 11:06:27.001 Info: Calling init methods on server "http://192.168.178.20".
11/17/18 11:06:27.905 Error in file RPC/RpcClient.cpp line 533 in function void Homegear::Rpc::RpcClient::sendRequest(Homegear::Rpc::RemoteRpcServer*, std::vector<char>&, std::vector<char>&, bool, bool&): Could not connect to server 192.168.178.20 on port 2000. Poll failed with error code: 1.
11/17/18 11:06:27.905 Removing server. Server has to send "init" again.
 [...]

Gruss vom Matze

#3

Wie sieht denn dazu das openhab Log aus?

#4

@macknife, formatier doch bitte deine Posting. Du kannst sie auch nachträglich editieren:

grafik

#5

Hallo,

ich habe jetzt mal die alten logfiles gelöscht und neugestartet. Die neuen logfiles lade ich hoch, aber hier schonmal ein Ausschnitt:

[...]
11/17/18 12:17:31.436 RPC Server (Port 2001): Info: Connection from ::ffff:127.0.0.1:41458 accepted. Client number: 20
11/17/18 12:17:31.437 RPC Server (Port 2001): Info: RPC server client id for client number 20 is: 3
11/17/18 12:17:31.438 RPC Server (Port 2001): Info: Client number 20 is calling RPC method: listDevices (2) Parameters:
11/17/18 12:17:31.513 RPC Server (Port 2001): Info: Client number 20 is calling RPC method: getDeviceInfo (2) Parameters:
11/17/18 12:17:31.526 RPC Server (Port 2001): Info: Client number 20 is calling RPC method: getParamsetDescription (2) Paramet
ers:
(String) OEQ2085078:0
(String) MASTER
11/17/18 12:17:31.651 RPC Server (Port 2001): Info: Client number 20 is calling RPC method: getParamsetDescription (2) Paramet
ers:
(String) OEQ2085078:0
[...]
(String) VALUES
11/17/18 12:17:36.124 RPC Server (Port 2001): Info: Client number 20 is calling RPC method: setValue (2) Parameters:
(String) OEQ1710378:3
(String) WINDOW_STATE
(Boolean) 0
11/17/18 12:17:36.485 Module HomeMatic BidCoS: TI CC110X "My-CC1101": Info: Sending (My-CC1101): 0C33B041FD0709638B06010100
11/17/18 12:17:36.634 Info: c-Frame is: 1133A002638B06FD0709042E0F05431B4B02
11/17/18 12:17:36.734 Module HomeMatic BidCoS: TI CC110X "My-CC1101": Info: Sending (My-CC1101): 1933A003FD0709638B060BBF5FA65
0415EB4FFA0F0F106403C3C
11/17/18 12:17:38.691 RPC Server (Port 2001): Info: Connection from ::ffff:127.0.0.1:41468 accepted. Client number: 22
11/17/18 12:17:38.692 RPC Server (Port 2001): Info: RPC server client id for client number 22 is: 4
11/17/18 12:17:38.694 RPC Server (Port 2001): Info: Client number 22 is calling RPC method: setValue (2) Parameters:
(String) OEQ1710378:3
(String) WINDOW_STATE
(Boolean) 0
11/17/18 12:17:41.734 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted
.
11/17/18 12:17:41.734 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 10). Retrying...
[...]

Und um 12:17:41 sagt openhab2.log:

[...]
2018-11-17 12:17:41.251 [ERROR] [ematic.handler.HomematicThingHandler] - -100 No answer from device. (sending setValue()
OEQ1710378:3
WINDOW_STATE
false
)
java.io.IOException: -100 No answer from device. (sending setValue()
OEQ1710378:3
WINDOW_STATE
false
)
	at org.openhab.binding.homematic.internal.communicator.parser.RpcResponseParser.parse(RpcResponseParser.java:47) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:78) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:90) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.BinRpcClient.sendMessage(BinRpcClient.java:66) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.client.RpcClient.setDatapointValue(RpcClient.java:298) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway$1.execute(AbstractHomematicGateway.java:672) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.misc.DelayedExecuter.start(DelayedExecuter.java:61) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.sendDatapoint(AbstractHomematicGateway.java:651) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.sendDatapoint(AbstractHomematicGateway.java:630) [208:org.openhab.binding.homematic:2.3.0]
	at org.openhab.binding.homematic.handler.HomematicThingHandler.handleCommand(HomematicThingHandler.java:276) [208:org.openhab.binding.homematic:2.3.0]
	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) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at com.sun.proxy.$Proxy143.handleCommand(Unknown Source) [208:org.openhab.binding.homematic:2.3.0]
	at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:75) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
	at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
	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) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]
	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) [?:?]

Gruss vom Matze

startopenhab2.log (10,5 KB)
starthomegear.log (182,0 KB)