openHAB 2 innogy SmartHome binding (ALPHA)

[neo2211]

Hallo Ollie,

bei mir das gleiche..Hat sich aufgebaut und hat auch funktionieret. Ab gestern abend dann  nix mehr (alles Online) aber schaltet nix.

Wie genau meinst Du das? In Deinem Log habe ich gesehen, dass der WebSocket sich wieder verbunden hat. Kannst Du aus OH nicht schalten oder kommen Schaltvorgänge von Geräten nicht bei OH an?

Einen Bug habe ich gefunden: wenn der access-token abläuft, muss der Websocket neu gestartet werden. Passiert bisher aber nicht. Außerdem beende ich den WebSocket nicht sauber. Da muss ich auch noch ran.
Bei mir ist das Binding nun 48 Stunden durchgelaufen und hakt erst seit dem Token-change.

Schaltvorgänge kommen von Geräten (Zuständen)  nicht an. Ich habe einen Zustand bei dem sobald das Licht angeht,  der Zustand auf an geht und über OH einen Befehl an sonos schickt. Das gleiche wenn das Licht per Schalter ausgeschaltet wird.  

Die Steuerung der Geräte über OH benutze ich augenblicklich nicht.

[ollie]

Okay. Dann liegts am WebSocket in Deinem Fall.

[frankz]

Hallo Frank,

ich kann das an dem Log nicht nachvollziehen. Es ist normal, dass die Websocket-Verbindung regelmäßig (teilweise alle 20 Minuten) beendet und neu aufgebaut wird. 

Wie verhält sich OH dann? Zwei Fälle sind interessant:
1. Kannst Du über OH noch Geräte schalten? -> OH kann aktiv über die API Geräte schalten
2. Kommen veränderte Geräte bei OH an? -> WebSocket läuft und bekommt Meldungen von innogy mit

Das wäre interessant. Danke!

Hallo Ollie,

zu 1. Nein, kann ich nicht.
zu 2. Der WebSocket läuft wohl nicht mehr. Es kommen dann auch nicht die regelmässigen PowerControlDaten.

Hier noch ein aktuelles Log dazu:

Code:

2016-12-11 10:47:07.495 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy Websocket session: WebSocketSession[websocket=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],behavior=CLIENT,connection=WebSocketClientConnection@158CC671{endp=DecryptedEndPoint@3191ac81{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,54/300000,WebSocketClientConnection@158cc671}->SelectChannelEndPoint@35ae2e6b{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,1/300000,SslConnection@70aca6ba}{io=0/0,kio=0,kro=1},ios=IOState@7ddf4b29[CONNECTED,!in,out],f=Flusher[queueSize=0,aggregateSize=0,failure=null],g=Generator[CLIENT,validating],p=Parser@419615b7[ExtensionStack,s=START,c=0,len=0,f=null]},remote=WebSocketRemoteEndpoint@50fa698[batching=true],incoming=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.webs...
2016-12-11 10:47:07.495 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy Websocket session: WebSocketSession[websocket=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],behavior=CLIENT,connection=WebSocketClientConnection@158CC671{endp=DecryptedEndPoint@3191ac81{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,54/300000,WebSocketClientConnection@158cc671}->SelectChannelEndPoint@35ae2e6b{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,1/300000,SslConnection@70aca6ba}{io=0/0,kio=0,kro=1},ios=IOState@7ddf4b29[CONNECTED,!in,out],f=Flusher[queueSize=0,aggregateSize=0,failure=null],g=Generator[CLIENT,validating],p=Parser@419615b7[ExtensionStack,s=START,c=0,len=0,f=null]},remote=WebSocketRemoteEndpoint@50fa698[batching=true],incoming=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.webs...
2016-12-11 10:47:07.495 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy Websocket session: WebSocketSession[websocket=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],behavior=CLIENT,connection=WebSocketClientConnection@158CC671{endp=DecryptedEndPoint@3191ac81{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,54/300000,WebSocketClientConnection@158cc671}->SelectChannelEndPoint@35ae2e6b{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,1/300000,SslConnection@70aca6ba}{io=0/0,kio=0,kro=1},ios=IOState@7ddf4b29[CONNECTED,!in,out],f=Flusher[queueSize=0,aggregateSize=0,failure=null],g=Generator[CLIENT,validating],p=Parser@419615b7[ExtensionStack,s=START,c=0,len=0,f=null]},remote=WebSocketRemoteEndpoint@50fa698[batching=true],incoming=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.webs...
2016-12-11 10:47:07.495 [DEBUG] [gysmarthome.internal.InnogyWebSocket] - innogy Websocket session: WebSocketSession[websocket=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],behavior=CLIENT,connection=WebSocketClientConnection@158CC671{endp=DecryptedEndPoint@3191ac81{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,54/300000,WebSocketClientConnection@158cc671}->SelectChannelEndPoint@35ae2e6b{api.services-smarthome.de/193.25.80.79:443<->51294,Open,in,out,-,-,1/300000,SslConnection@70aca6ba}{io=0/0,kio=0,kro=1},ios=IOState@7ddf4b29[CONNECTED,!in,out],f=Flusher[queueSize=0,aggregateSize=0,failure=null],g=Generator[CLIENT,validating],p=Parser@419615b7[ExtensionStack,s=START,c=0,len=0,f=null]},remote=WebSocketRemoteEndpoint@50fa698[batching=true],incoming=JettyAnnotatedEventDriver[org.openhab.binding.innogysmarthome.internal.InnogyWebSocket@3f56451b],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.webs...
2016-12-11 10:48:49.249 [DEBUG] [marthome.handler.InnogyDeviceHandler] - handleCommand called for channel 'innogysmarthome:PSS:SMARTHOME03:a2eb10917b1a437ea2e2f1d54704cd6e:switch' of type 'PSS' with command 'ON'
2016-12-11 10:48:49.249 [DEBUG] [marthome.handler.InnogyDeviceHandler] - handleCommand called for channel 'innogysmarthome:PSS:SMARTHOME03:a2eb10917b1a437ea2e2f1d54704cd6e:switch' of type 'PSS' with command 'ON'
2016-12-11 10:48:51.008 [ERROR] [marthome.handler.InnogyBridgeHandler] - Unexcepted API error: Session not found
2016-12-11 10:48:51.022 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
2016-12-11 10:48:51.023 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'SMARTHOME03'
2016-12-11 10:48:51.023 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'SMARTHOME03'
2016-12-11 10:48:51.031 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSS:SMARTHOME03:a2eb10917b1a437ea2e2f1d54704cd6e bridge status OFFLINE
2016-12-11 10:48:51.031 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSS:SMARTHOME03:a2eb10917b1a437ea2e2f1d54704cd6e bridge status OFFLINE
2016-12-11 10:48:51.023 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found

Als Attachment ein längeres Log.

[thefox73]

Hi Ollie,

während des Startvorgangs von OH kommt (häufiger) dieser Fehler

Code:

13:33:09.606 [ERROR] [marthome.handler.InnogyBridgeHandler] - Unexcepted API error: Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $

Evtl. ein hinweis für Dich?

Interessant. Hast Du DEBUG an und kannst mir noch mehr Zeilen vorher schicken? Sowas hatte ich noch nicht.

Moin Ollie,

hier ein paar Zeilen aus dem DEBUG-Log

Code:

2016-12-09 12:36:10.391 [DEBUG] [home.internal.DeviceStructureManager] - getDeviceById fa07529589dd49f88189238b5383d356:true
2016-12-09 12:36:10.391 [DEBUG] [marthome.handler.InnogyDeviceHandler] - onDeviceStateChanged called with device DG_AUF_AB/fa07529589dd49f88189238b5383d356
2016-12-09 12:36:10.391 [DEBUG] [marthome.handler.InnogyDeviceHandler] - ->capability:2c9dcd8705904a5a9949987193c103da (BooleanStateActuator/Boolean State Actuator)
2016-12-09 12:36:10.399 [DEBUG] [marthome.handler.InnogyDeviceHandler] - handleCommand called for channel 'innogysmarthome:PSS:SMARTHOME06:87f1d188d8f042d68fb5a59547e7571d:switch' of type 'PSS' with command 'REFRESH'
2016-12-09 12:36:12.793 [ERROR] [marthome.handler.InnogyBridgeHandler] - Unexcepted API error: Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.794 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.794 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.795 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.794 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.796 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:WDS:SMARTHOME06:608c1b5290dc462e855f5dd895e749d0 bridge status OFFLINE
2016-12-09 12:36:12.796 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:WDS:SMARTHOME06:3269a606626a45e4a1251a8389b637ca bridge status OFFLINE
2016-12-09 12:36:12.796 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'SMARTHOME06'
2016-12-09 12:36:12.800 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.800 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.800 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:WDS:SMARTHOME06:0622927906ef4a6e97f8ccaf083cc73f bridge status OFFLINE
2016-12-09 12:36:12.800 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSSO:SMARTHOME06:7a895f5ad58745fd9edf75cf3cf7da76 bridge status OFFLINE
2016-12-09 12:36:12.795 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.810 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.812 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:WDS:SMARTHOME06:f4999693708d4fb2858e03a2fe21ca4c bridge status OFFLINE
2016-12-09 12:36:12.813 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.810 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $
2016-12-09 12:36:12.814 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:VariableActuator:SMARTHOME06:4765b20aed9a4ac680e4e6fe734a33c9 bridge status OFFLINE
2016-12-09 12:36:12.796 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:ISS2:SMARTHOME06:f89771e2cae44df4affbf1b3a9eec344 bridge status OFFLINE
2016-12-09 12:36:12.796 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:VariableActuator:SMARTHOME06:be7e9a46b6214665aa2b421a5e9f1ce1 bridge status OFFLINE

[Jörg Steen]

Moin Moin,

ich versuche nun schon eine ganze Weile das OH2 auf Windows 10 mit diesem Binding zum Laufen zu bekommen.
Habe hier vorwärts und rückwärts gelesen und verzweifle.
Die OH2 Maske in der ich die PaperUI bekomme scheint zu laufen. Das Smarthome Teil kann ich finden und bestätigen.
Den Code scheine ich auch zu bekommen, trage diese auch mit Copy&Past ein.
Dann aber erhalte ich: UNINITIALIZED - HANDLER_REGISTERING_ERROR.
Habe ich noch irgendetwas vergessen, überlesen oder völlig falsch gemacht?

[ollie]

Moin Moin,

ich versuche nun schon eine ganze Weile das OH2 auf Windows 10 mit diesem Binding zum Laufen zu bekommen.
Habe hier vorwärts und rückwärts gelesen und verzweifle.
Die OH2 Maske in der ich die PaperUI bekomme scheint zu laufen. Das Smarthome Teil kann ich finden und bestätigen.
Den Code scheine ich auch zu bekommen, trage diese auch mit Copy&Past ein.
Dann aber erhalte ich: UNINITIALIZED - HANDLER_REGISTERING_ERROR.
Habe ich noch irgendetwas vergessen, überlesen oder völlig falsch gemacht?

Hallo Jörg,

bitte aktiviere das DEBUG-Log. Ohne Logeinträge ist es sehr schwer zu helfen. Bitte stell sicher, dass Du eine aktuelle Snapshot-Version von openHAB2 installiert hast. Das kann ggf. auch an Versionsinkompatibilitäten liegen (Mutmaßung...).

[ollie]

Heute morgen hat das Update bei innogy im Backend bei mir noch diverse Spätfolgen gehabt mit IO read errors etc. Dabei kam es zu einem Feuerwerk an Fehlermeldungen - also wundert euch nicht. Klar ist, dass das Binding auch sowas auf Dauer aushalten und überstehen muss. Ich habe in den Logs schon Optimierungspotential gefunden...

Aktuell startet ihr am Besten OH einfach mal neu, damit ihr einen stabilen Status habt.

[ollie]

Hi Ollie,

während des Startvorgangs von OH kommt (häufiger) dieser Fehler

Code:

13:33:09.606 [ERROR] [marthome.handler.InnogyBridgeHandler] - Unexcepted API error: Invalid JSON syntax in error response: java.lang.IllegalStateException: Expected BEGIN_OBJECT but was STRING at line 1 column 2 path $

Evtl. ein hinweis für Dich?

Interessant. Hast Du DEBUG an und kannst mir noch mehr Zeilen vorher schicken? Sowas hatte ich noch nicht.

Moin Ollie,

hier ein paar Zeilen aus dem DEBUG-Log

Code:

2016-12-09 12:36:10.391 [DEBUG] [home.internal.DeviceStructureManager] - getDeviceById fa07529589dd49f88189238b5383d356:true...

Moin,
leider genügen die Debug-Logs bisher nicht. Ich habe hier im Binding noch mehr DEBUG output in der innogy-Library drin:
http://www.ollie.in/wp-content/uploads/2...161211.jar
Bitte nimm die Version und aktiviere das DEBUG Logging für die Library, indem Du den folgenden Text in die Datei org.ops4j.pax.logging.cfg einfügst:
log4j.logger.in.ollie.innogysmarthome = DEBUG

Dann gibt er bei Deinem Fehler den JSON-Content aus, den er zurückbekommen hat. Bin gespannt, was da kommt. Aber vielleicht schaust Du vorher auch nochmal, ob es den Fehler seit den Wartungsarbeiten bei innogy heute Nacht überhaupt noch gibt.

Bei mir läuft die Version, allerdings hatte ich beim ersten Installieren deutliche Fehler gestern Abend. Ich bin nicht ganz sicher, ob innogy schon am Backend rumgefummelt hat oder ob es am OH2-Snapshot lag. Ich habe dann den neusten OH2 Snapshot installiert. Falls es also nicht direkt funktioniert, bitte nimm den letzten Snapshot. 

Übrigens empfiehlt es sich, in der addons.cfg die genutzten Bindings, UIs etc einzutragen. Zumindest unter Debian installiert er dann immer brav die bindings automatisch...

[neo2211]

Hallo Ollie,
Hast du am websocket auch was geändert oder nur die Logs erweitert?
Vg

[re-vo-lution]

Hi Ollie,

Kann man die Bateriewerte bzw Batteriewarnungen in OH2 auslesen?

VG Rene