[innogy] Update to new API 1.1 and new devices tested

sayhelloto · September 27, 2019, 12:44pm

Hi Ollie, thank you for your great work on this.

I think there is a tiny bug in your binding I want to report:

When I press a button on the 8 button remote control its recognized the first time I press a button. But if I press the very same button (lets say button 7) a second time the press is not being recognized. No entries in the log except a battery warning. When I press another button (lets say 6) I can press 7 again and its being recognized. Thats how the log looks if I press the same button 2 times.

2019-09-27 14:38:36.859 [vent.ChannelTriggeredEvent] - innogysmarthome:BRC8:SMARTHOME05:91145d7daba6456a9df17978c5a59aaf:button8 triggered PRESSED`

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

2019-09-27 14:38:36.861 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler tried updating the thing status although the handler was already disposed.

2019-09-27 14:38:36.866 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler of thing innogysmarthome:BRC8:SMARTHOME05:91145d7daba6456a9df17978c5a59aaf tried updating channel battery_low although the handler was already disposed.

2019-09-27 14:38:49.714 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler tried updating the thing status although the handler was already disposed.

2019-09-27 14:38:49.723 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler of thing innogysmarthome:BRC8:SMARTHOME05:91145d7daba6456a9df17978c5a59aaf tried updating channel battery_low although the handler was already disposed.
 2019-09-27 14:38:49.738 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler tried updating the thing status although the handler was already disposed.
2019-09-27 14:38:49.745 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler InnogyDeviceHandler of thing innogysmarthome:BRC8:SMARTHOME05:91145d7daba6456a9df17978c5a59aaf tried updating channel battery_low although the handler was already disposed.

oliver_kuhl · September 29, 2019, 9:28am

Hi @rene54321, sorry for the late reply. Could you please set log level to TRACE and then do the following:

Set the target temperature to a new value on the WRT
Change the target temperature again on the innogy smartphone app (to see if OH updates)
Wait, until humidity or current temperature changed

Please watch the logs while doing those tests and send me the logs (if you like per direct message) ideally with some markup of when you did the action above.

Thx! And sorry I did not answer earlier. I am very busy currently and above all my old Macs Keyboard crashed and I had to replace it…

oliver_kuhl · September 29, 2019, 9:29am

Thanks for the update. Really helpful!

oliver_kuhl · September 29, 2019, 9:32am

Elias, could you please enable TRACE log and send me the logs after you tried the same button pushes? Thanks!

sayhelloto · September 29, 2019, 11:36am

Hi Ollie, please find the trace log attached. This time with button 1. That problem seems to exist on any device with buttons, not limited to the remote control.

019-09-29 13:23:28.036 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3083,"type":"StateChanged","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:27.9939000Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"lastKeyPressCounter":1}}

2019-09-29 13:23:28.041 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:28.046 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3083,"type":"StateChanged","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:27.9939000Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"lastKeyPressCounter":1}}

2019-09-29 13:23:28.052 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3083 found. Type: StateChanged

2019-09-29 13:23:28.061 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:28.066 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab relevant for this handler.

2019-09-29 13:23:28.070 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability PushButtonSensor, Fernbedienung 1 with id 3959f109c837436a8fe3016c9fa426a4, device /device/5aa1dcc043ac4e58be7b85d276711fab from device id 5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:28.074 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Fernbedienung 1/5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:28.082 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:3959f109c837436a8fe3016c9fa426a4 (PushButtonSensor/Fernbedienung 1)

2019-09-29 13:23:28.086 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index null count 1

2019-09-29 13:23:28.090 [DEBUG] [internal.handler.InnogyBridgeHandler] - Error with Event: null

java.lang.NullPointerException: null

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyDeviceHandler.onDeviceStateChanged(InnogyDeviceHandler.java:570) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyDeviceHandler.onDeviceStateChanged(InnogyDeviceHandler.java:877) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler.handleStateChangedEvent(InnogyBridgeHandler.java:625) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler.onEvent(InnogyBridgeHandler.java:559) [254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.InnogyWebSocket.onMessage(InnogyWebSocket.java:148) [254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at sun.reflect.GeneratedMethodAccessor53.invoke(Unknown Source) ~[?:?]

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

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

	at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.annotated.OptionalSessionCallableMethod.call(OptionalSessionCallableMethod.java:72) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onTextMessage(JettyAnnotatedEventDriver.java:234) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.message.SimpleTextMessage.messageComplete(SimpleTextMessage.java:69) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.appendMessage(AbstractEventDriver.java:66) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onTextFrame(JettyAnnotatedEventDriver.java:226) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.incomingFrame(AbstractEventDriver.java:162) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.WebSocketSession.incomingFrame(WebSocketSession.java:476) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.extensions.ExtensionStack.incomingFrame(ExtensionStack.java:220) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.Parser.notifyFrame(Parser.java:220) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.Parser.parse(Parser.java:245) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.readParse(AbstractWebSocketConnection.java:560) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onFillable(AbstractWebSocketConnection.java:391) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]

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

2019-09-29 13:23:28.103 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:28.412 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3084,"type":"ButtonPressed","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/ButtonPressed","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:27.9954530Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"index":0,"keyPressCounter":1,"lastPressedButtonIndex":0,"type":"ShortPress"}}

2019-09-29 13:23:28.416 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:28.419 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3084,"type":"ButtonPressed","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/ButtonPressed","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:27.9954530Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"index":0,"keyPressCounter":1,"lastPressedButtonIndex":0,"type":"ShortPress"}}

2019-09-29 13:23:28.423 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3084 found. Type: ButtonPressed

2019-09-29 13:23:28.427 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:28.430 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab relevant for this handler.

2019-09-29 13:23:28.434 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability PushButtonSensor, Fernbedienung 1 with id 3959f109c837436a8fe3016c9fa426a4, device /device/5aa1dcc043ac4e58be7b85d276711fab from device id 5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:28.441 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Fernbedienung 1/5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:28.448 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:3959f109c837436a8fe3016c9fa426a4 (PushButtonSensor/Fernbedienung 1)

2019-09-29 13:23:28.455 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index 0 count null

2019-09-29 13:23:28.458 [DEBUG] [internal.handler.InnogyDeviceHandler] - State for PushButtonSensor is STILL NULL!! cstate-id: 3959f109c837436a8fe3016c9fa426a4, c-id: 3959f109c837436a8fe3016c9fa426a4

2019-09-29 13:23:28.461 [TRACE] [internal.handler.InnogyBridgeHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab not relevant for this handler (responsible for id 00000000000000000000000000000000)

2019-09-29 13:23:28.464 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:30.274 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3085,"type":"StateChanged","desc":"/desc/device/ISD2.RWE/1.0/capability/DimmerActuator/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:30.2260720Z","source":"/capability/5b24e65f24164c57b4b6b8d1b54068e3","properties":{"dimLevel":0}}

2019-09-29 13:23:30.278 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:30.280 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3085,"type":"StateChanged","desc":"/desc/device/ISD2.RWE/1.0/capability/DimmerActuator/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:30.2260720Z","source":"/capability/5b24e65f24164c57b4b6b8d1b54068e3","properties":{"dimLevel":0}}

2019-09-29 13:23:30.283 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3085 found. Type: StateChanged

2019-09-29 13:23:30.287 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:30.291 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId e45e5bc6848b4dc6b5bac1a575271fa8 relevant for this handler.

2019-09-29 13:23:30.294 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability DimmerActuator, Dimmer Actuator with id 5b24e65f24164c57b4b6b8d1b54068e3, device /device/e45e5bc6848b4dc6b5bac1a575271fa8 from device id e45e5bc6848b4dc6b5bac1a575271fa8

2019-09-29 13:23:30.296 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Deckenlampe/e45e5bc6848b4dc6b5bac1a575271fa8

2019-09-29 13:23:30.300 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:216e9872b3f74776a0da5d3b047adacd (PushButtonSensor/ISD2)

2019-09-29 13:23:30.304 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index 1 count null

2019-09-29 13:23:30.307 [DEBUG] [internal.handler.InnogyDeviceHandler] - State for PushButtonSensor is STILL NULL!! cstate-id: 216e9872b3f74776a0da5d3b047adacd, c-id: 216e9872b3f74776a0da5d3b047adacd

2019-09-29 13:23:30.310 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:5b24e65f24164c57b4b6b8d1b54068e3 (DimmerActuator/Dimmer Actuator)

2019-09-29 13:23:30.313 [DEBUG] [internal.handler.InnogyDeviceHandler] - Dimlevel state 0

2019-09-29 13:23:30.326 [TRACE] [internal.handler.InnogyBridgeHandler] - DeviceId e45e5bc6848b4dc6b5bac1a575271fa8 not relevant for this handler (responsible for id 00000000000000000000000000000000)

2019-09-29 13:23:30.331 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

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

2019-09-29 13:23:30.337 [vent.ItemStateChangedEvent] - innogysmarthome_ISD2_SMARTHOME10_e45e5bc6848b4dc6b5bac1a575271fa8_dimmer changed from 100 to 0

2019-09-29 13:23:36.975 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3086,"type":"StateChanged","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:36.9307040Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"lastKeyPressCounter":2}}

2019-09-29 13:23:36.979 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:36.982 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3086,"type":"StateChanged","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:36.9307040Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"lastKeyPressCounter":2}}

2019-09-29 13:23:36.985 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3086 found. Type: StateChanged

2019-09-29 13:23:36.989 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:36.993 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab relevant for this handler.

2019-09-29 13:23:36.996 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability PushButtonSensor, Fernbedienung 1 with id 3959f109c837436a8fe3016c9fa426a4, device /device/5aa1dcc043ac4e58be7b85d276711fab from device id 5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:36.999 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Fernbedienung 1/5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:37.030 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:3959f109c837436a8fe3016c9fa426a4 (PushButtonSensor/Fernbedienung 1)

2019-09-29 13:23:37.033 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index null count 2

2019-09-29 13:23:37.037 [DEBUG] [internal.handler.InnogyBridgeHandler] - Error with Event: null

java.lang.NullPointerException: null

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyDeviceHandler.onDeviceStateChanged(InnogyDeviceHandler.java:570) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyDeviceHandler.onDeviceStateChanged(InnogyDeviceHandler.java:877) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler.handleStateChangedEvent(InnogyBridgeHandler.java:625) ~[254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler.onEvent(InnogyBridgeHandler.java:559) [254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at org.openhab.binding.innogysmarthome.internal.InnogyWebSocket.onMessage(InnogyWebSocket.java:148) [254:org.openhab.binding.innogysmarthome:2.4.0.201908172111]

	at sun.reflect.GeneratedMethodAccessor53.invoke(Unknown Source) ~[?:?]

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

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

	at org.eclipse.jetty.websocket.common.events.annotated.CallableMethod.call(CallableMethod.java:71) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.annotated.OptionalSessionCallableMethod.call(OptionalSessionCallableMethod.java:72) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onTextMessage(JettyAnnotatedEventDriver.java:234) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.message.SimpleTextMessage.messageComplete(SimpleTextMessage.java:69) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.appendMessage(AbstractEventDriver.java:66) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver.onTextFrame(JettyAnnotatedEventDriver.java:226) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.events.AbstractEventDriver.incomingFrame(AbstractEventDriver.java:162) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.WebSocketSession.incomingFrame(WebSocketSession.java:476) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.extensions.ExtensionStack.incomingFrame(ExtensionStack.java:220) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.Parser.notifyFrame(Parser.java:220) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.Parser.parse(Parser.java:245) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.readParse(AbstractWebSocketConnection.java:560) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onFillable(AbstractWebSocketConnection.java:391) [92:org.eclipse.jetty.websocket.common:9.4.11.v20180605]

	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]

	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]

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

2019-09-29 13:23:37.049 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:37.355 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3087,"type":"ButtonPressed","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/ButtonPressed","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:36.9324980Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"index":0,"keyPressCounter":2,"lastPressedButtonIndex":0,"type":"ShortPress"}}

2019-09-29 13:23:37.358 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:37.361 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3087,"type":"ButtonPressed","desc":"/desc/device/BRC8.RWE/1.0/capability/PushButtonSensor/event/ButtonPressed","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:36.9324980Z","source":"/capability/3959f109c837436a8fe3016c9fa426a4","properties":{"index":0,"keyPressCounter":2,"lastPressedButtonIndex":0,"type":"ShortPress"}}

2019-09-29 13:23:37.365 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3087 found. Type: ButtonPressed

2019-09-29 13:23:37.369 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:37.372 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab relevant for this handler.

2019-09-29 13:23:37.376 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability PushButtonSensor, Fernbedienung 1 with id 3959f109c837436a8fe3016c9fa426a4, device /device/5aa1dcc043ac4e58be7b85d276711fab from device id 5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:37.379 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Fernbedienung 1/5aa1dcc043ac4e58be7b85d276711fab

2019-09-29 13:23:37.386 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:3959f109c837436a8fe3016c9fa426a4 (PushButtonSensor/Fernbedienung 1)

2019-09-29 13:23:37.408 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index 0 count null

2019-09-29 13:23:37.410 [DEBUG] [internal.handler.InnogyDeviceHandler] - State for PushButtonSensor is STILL NULL!! cstate-id: 3959f109c837436a8fe3016c9fa426a4, c-id: 3959f109c837436a8fe3016c9fa426a4

2019-09-29 13:23:37.412 [TRACE] [internal.handler.InnogyBridgeHandler] - DeviceId 5aa1dcc043ac4e58be7b85d276711fab not relevant for this handler (responsible for id 00000000000000000000000000000000)

2019-09-29 13:23:37.414 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:39.116 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy WebSocket onMessage() - {"sequenceNumber":3088,"type":"StateChanged","desc":"/desc/device/ISD2.RWE/1.0/capability/DimmerActuator/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:39.0741100Z","source":"/capability/5b24e65f24164c57b4b6b8d1b54068e3","properties":{"dimLevel":100}}

2019-09-29 13:23:39.117 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

2019-09-29 13:23:39.119 [TRACE] [internal.handler.InnogyBridgeHandler] - onEvent called. Msg: {"sequenceNumber":3088,"type":"StateChanged","desc":"/desc/device/ISD2.RWE/1.0/capability/DimmerActuator/event/StateChanged","namespace":"CosipDevices.RWE","timestamp":"2019-09-29T11:23:39.0741100Z","source":"/capability/5b24e65f24164c57b4b6b8d1b54068e3","properties":{"dimLevel":100}}

2019-09-29 13:23:39.122 [DEBUG] [internal.handler.InnogyBridgeHandler] - Event no 3088 found. Type: StateChanged

2019-09-29 13:23:39.125 [TRACE] [internal.handler.InnogyBridgeHandler] - Event is linked to capability

2019-09-29 13:23:39.127 [TRACE] [internal.handler.InnogyDeviceHandler] - DeviceId e45e5bc6848b4dc6b5bac1a575271fa8 relevant for this handler.

2019-09-29 13:23:39.130 [TRACE] [internal.handler.InnogyDeviceHandler] - Loaded Capability DimmerActuator, Dimmer Actuator with id 5b24e65f24164c57b4b6b8d1b54068e3, device /device/e45e5bc6848b4dc6b5bac1a575271fa8 from device id e45e5bc6848b4dc6b5bac1a575271fa8

2019-09-29 13:23:39.132 [DEBUG] [internal.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device Deckenlampe/e45e5bc6848b4dc6b5bac1a575271fa8

2019-09-29 13:23:39.135 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:216e9872b3f74776a0da5d3b047adacd (PushButtonSensor/ISD2)

2019-09-29 13:23:39.138 [DEBUG] [internal.handler.InnogyDeviceHandler] - Pushbutton index 1 count null

2019-09-29 13:23:39.140 [DEBUG] [internal.handler.InnogyDeviceHandler] - State for PushButtonSensor is STILL NULL!! cstate-id: 216e9872b3f74776a0da5d3b047adacd, c-id: 216e9872b3f74776a0da5d3b047adacd

2019-09-29 13:23:39.143 [DEBUG] [internal.handler.InnogyDeviceHandler] - ->capability:5b24e65f24164c57b4b6b8d1b54068e3 (DimmerActuator/Dimmer Actuator)

2019-09-29 13:23:39.145 [DEBUG] [internal.handler.InnogyDeviceHandler] - Dimlevel state 100

2019-09-29 13:23:39.152 [TRACE] [internal.handler.InnogyBridgeHandler] - DeviceId e45e5bc6848b4dc6b5bac1a575271fa8 not relevant for this handler (responsible for id 00000000000000000000000000000000)
2019-09-29 13:23:39.154 [TRACE] [internal.handler.InnogyBridgeHandler] - =====================================================

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

2019-09-29 13:23:39.165 [vent.ItemStateChangedEvent] - innogysmarthome_ISD2_SMARTHOME10_e45e5bc6848b4dc6b5bac1a575271fa8_dimmer changed from 0 to 100

rene54321 · September 29, 2019, 3:11pm

Hi,

I have changed the log level to trace as follows:

openhab> list | grep innogy
202 x Active x 80 x 2.4.0.201908172111 x innogy SmartHome Binding
openhab> log:set TRACE 202
openhab> log:get 202
TRACE
openhab>

But there is no message received in the log after changing the temp on the thermostat or humidity and temp changes on the thermostat. The only thing what is working is to change the temp from openhab towards to the thermostats.

best regards René

RalphSester · October 1, 2019, 6:41am

this is the same error than i reported. i can confirm this.

suntribe · October 7, 2019, 1:52am

Hi @oliver_kuhl, i have another issue… look at these logs…

2019-10-07 03:48:49.956 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Initializing innogy SmartHome BridgeHandler...
2019-10-07 03:48:49.957 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - innogy config: Config [clientId=24635748, clientSecret=no secret, redirectUrl=https://www.openhab.org/oauth/innogy/innogy-smarthome.html, authCode=, accessToken=eyJ0eXAiOi...0nmHozVFIA, refreshToken=8c58c...9a0cd]
2019-10-07 03:48:49.957 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Initializing innogy SmartHome client...
2019-10-07 03:48:49.957 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - Get innogy SmartHome status...
2019-10-07 03:48:50.498 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - [1] Statuscode is OK.
2019-10-07 03:48:50.498 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - innogy SmartHome Status loaded. Configuration version is 1004.
2019-10-07 03:48:50.499 [DEBUG] [ysmarthome.internal.manager.DeviceStructureManager] - Starting device structure manager.
2019-10-07 03:48:50.499 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - Loading locations...
2019-10-07 03:48:50.591 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - [2] Statuscode is OK.
2019-10-07 03:48:50.592 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - Loading capabilities...
2019-10-07 03:48:50.723 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - [3] Statuscode is OK.
2019-10-07 03:48:50.724 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - Loading capability states...
2019-10-07 03:48:51.585 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - [4] Statuscode is OK.
2019-10-07 03:48:51.586 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - Loading device states...
2019-10-07 03:48:52.212 [DEBUG] [nding.innogysmarthome.internal.client.InnogyClient] - [5] Statuscode is OK.
2019-10-07 03:48:52.213 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Unknown exception
java.lang.IllegalArgumentException: key updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:382) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:355) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonObjectParser.parseAndClose(JsonObjectParser.java:87) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonObjectParser.parseAndClose(JsonObjectParser.java:81) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.http.HttpResponse.parseAs(HttpResponse.java:459) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.client.InnogyClient.getDeviceStates(InnogyClient.java:761) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.client.InnogyClient.getFullDevices(InnogyClient.java:588) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.manager.DeviceStructureManager.refreshDevices(DeviceStructureManager.java:85) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.manager.DeviceStructureManager.start(DeviceStructureManager.java:64) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler$Initializer.run(InnogyBridgeHandler.java:132) [250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
	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) [?:?]
Caused by: java.lang.IllegalArgumentException: key updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: key updateAvailable, field private org.openhab.binding.innogysmarthome.internal.client.entity.device.State org.openhab.binding.innogysmarthome.internal.client.entity.device.DeviceState.state
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: key updateAvailable, field private org.openhab.binding.innogysmarthome.internal.client.entity.state.StringState org.openhab.binding.innogysmarthome.internal.client.entity.device.State.updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: expected primitive class, but got: class org.openhab.binding.innogysmarthome.internal.client.entity.state.StringState
	at com.google.api.client.util.Data.parsePrimitiveValue(Data.java:453) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:859) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
2019-10-07 03:48:52.231 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'ishbridge'
2019-10-07 03:48:52.231 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - innogy SmartHome bridge handler shut down.
2019-10-07 03:48:52.231 [DEBUG] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Scheduling reinitialize in 30 seconds.
2019-10-07 03:48:52.231 [ERROR] [nogysmarthome.internal.handler.InnogyBridgeHandler] - Error starting device structure manager.
java.lang.IllegalArgumentException: key updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:382) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:355) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonObjectParser.parseAndClose(JsonObjectParser.java:87) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.json.JsonObjectParser.parseAndClose(JsonObjectParser.java:81) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at com.google.api.client.http.HttpResponse.parseAs(HttpResponse.java:459) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.client.InnogyClient.getDeviceStates(InnogyClient.java:761) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.client.InnogyClient.getFullDevices(InnogyClient.java:588) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.manager.DeviceStructureManager.refreshDevices(DeviceStructureManager.java:85) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.manager.DeviceStructureManager.start(DeviceStructureManager.java:64) ~[250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at org.openhab.binding.innogysmarthome.internal.handler.InnogyBridgeHandler$Initializer.run(InnogyBridgeHandler.java:132) [250:org.openhab.binding.innogysmarthome:2.4.0.201907121857]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
	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) [?:?]
Caused by: java.lang.IllegalArgumentException: key updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: key updateAvailable, field private org.openhab.binding.innogysmarthome.internal.client.entity.device.State org.openhab.binding.innogysmarthome.internal.client.entity.device.DeviceState.state
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: key updateAvailable, field private org.openhab.binding.innogysmarthome.internal.client.entity.state.StringState org.openhab.binding.innogysmarthome.internal.client.entity.device.State.updateAvailable
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:889) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more
Caused by: java.lang.IllegalArgumentException: expected primitive class, but got: class org.openhab.binding.innogysmarthome.internal.client.entity.state.StringState
	at com.google.api.client.util.Data.parsePrimitiveValue(Data.java:453) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:859) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parse(JsonParser.java:472) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:781) ~[?:?]
	at com.google.api.client.json.JsonParser.parseArray(JsonParser.java:648) ~[?:?]
	at com.google.api.client.json.JsonParser.parseValue(JsonParser.java:740) ~[?:?]
	... 17 more

the binding simply stops working at some point, and these logs are endlessly repeating… I tryed multiple times to remove the binding and add it back again, but no luck. any clue on what might be wrong here? I’m on oh2.4

jlehre · October 11, 2019, 3:40pm

I upgraded my central from V1.0 to V2.0 today.
All items in OH seem to work as expected except the RST.
I can send commands to it but I do not get updates from the RST (set_tempature, temperature, humidity).
In the trace the innogy binding gets exceptions when updates are sent from SHC to OH. I already sent a trace to Ollie.

jlehre · October 11, 2019, 3:59pm

and here is a workaround using rules in OH. It will refresh the values every 60 seconds.

rule “refreshRST”
when
Time cron “0 * * * * ? *”
then
PARTYKELLERIst.sendCommand(“REFRESH”)
PARTYKELLERSoll.sendCommand(“REFRESH”)
end

oliver_kuhl · October 11, 2019, 7:16pm

Hey all,

sorry - I must admit that I don’t have the time to work on the binding. I struggle for weeks now, had several issues with my Laptop and Development environment and - what is the most important part - not the time and power right now to do programming late in the evenings as I am too busy with personal and business stuff.

I uploaded the latest source code of the OH 2.4.0 innogy binding including the new API 1.1 version to github here so if anyone has the time to work on it is free to do so.

I really wish I had the time, but it currently makes no sense. So I hope there is someone who can work on it to fix the bugs. The API 1.1 had severe changes and I had to rework a lot of stuff. This is why further tests and bugfixes will be necessary.

If someone works on it, please contact me and I will send you some internal API documentation that innogy shared with me.

So sorry again I cannot support right now. Maybe in a couple of months when some of the other things in my life are done I am able to step in again.

Best Ollie

hilbrand · October 11, 2019, 9:26pm

Life happens. But good to state your boundaries.

For anyone interested in working on this binding. I provided to Ollie a version that uses the openHAB OAuth2 implementation which is based on a current version of the binding in master, and is based on the new build system. My suggestion would be to start with that and copy in the code from Ollies branch related to the API. Here is the background information:

Here is a first implementation. It’s not completely cleaned up. But should contain all functionality. I can’t test it as I don’t have innogy. The code is in this innogy branch and I’ve put a jar on github.
If you run in eclipse it will need re-authentication every time debug in eclipse is started. This is because refresh and accesstoken are stored in secured storage that uses a random key. This key is reset at startup and in eclipse debug and therefor looses the refresh/accesstoken information.
The refresh/accesstoken is still stored in the bridge config. I didn’t change that part. But that information in the bridge configuration isn’t used by the binding.

If someone is interested in working on this binding feel free to ask me for advice related to the authentication part.

jlehre · October 12, 2019, 5:35am

@Ollie: So sad to hear. BUT you have contributed so much during the last years. Applause and kudos for this. As you state there is more important things in live.

All the best!

rene54321 · October 12, 2019, 3:36pm

Hi,

first of all big thanks to Oli for this wonderful piece of work. I hope there are some other programmers out there to push the Innogy binding further and fix the open issues. Thumbs pressed .

best regards René

rene54321 · October 12, 2019, 3:43pm

Hi Jürgen,

thanks for this workaround, seems to work

best regards René

jlehre · October 12, 2019, 3:49pm

too sad that Ollie can’t work on this anymore. I use the integration heavily and I don’t want to loose the innogy OH support. Latest with OH 2.5 we won’t be able to migrate

I can spend some time but I am not a good programmer and light years away from being a Java programmer.
I already wrote to Livisi (or whatever former innogy is named) why they can’t take it on.

weakfl · October 12, 2019, 4:02pm

Current version is working fine with OH 2.5, the problem is that Innogy will be deprecating the API version sooner or later.

Thanks @oliver_kuhl for all your work. I would have ditched my RSTs long ago without your OH integration. Going forward that’s probably what I’ll (have to) do anyway…

Tobi77 · October 12, 2019, 6:58pm

First of all, I would like to express my gratitude to @oliver_kuhl!

Would it be reasonable to create a call for a new maintainer to finalize the binding for the new API?

I mean, I am not sure whether all users of the innogy binding know about the situation. And maybe one of them is willing to contribute…

What do you think?

antonymarc · October 14, 2019, 2:38pm

Thanks all for your responses. i was having similar questions and your answers were helpful to me.

sayhelloto · October 14, 2019, 4:07pm

Thank you, seems to work as well if you refresh only one item of a thing.
Unfortunately such a workaround doesn’t seem to work for my button pushing problem. Until a fix is known I’m going to use variables in innogy smarthome and react to changes made to them in openhab.