20-12-2016, 09:20:09
(20-12-2016, 01:57:44)sboldt schrieb: Leider bin ich mit der neuen Version nach kurzer Zeit direkt wieder in Probleme gelaufen:
Code:00:54:49.021 [INFO ] [smarthome.event.ItemCommandEvent ] - Item 'Switch_test' received command OFF
00:54:49.024 [DEBUG] [marthome.handler.InnogyDeviceHandler] - handleCommand called for channel 'innogysmarthome:PSS:SMARTHOME09:a630fcb0db7d4366813b5799bae09841:switch' of type 'PSS' with command 'OFF'
00:54:49.032 [DEBUG] [home.internal.DeviceStructureManager] - getDeviceById a630fcb0db7d4366813b5799bae09841:true
00:54:49.051 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Action toggle JSON: {"type":"device/SHC.RWE/1.0/action/SetState","Link":{"value":"/capability/f1bdf82cb8af42c485f65ed7bb745aed"},"Data":[{"name":"OnState","type":"/entity/Constant","constant":{"value":false}}]}
00:54:54.026 [WARN ] [ome.core.thing.internal.ThingManager] - Handler for thing 'innogysmarthome:PSS:SMARTHOME09:a630fcb0db7d4366813b5799bae09841' takes more than 5000ms for processing event
00:54:54.026 [WARN ] [ore.internal.events.OSGiEventManager] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.ThingManager@3ce85427' takes more than 5000ms.
00:54:54.028 [WARN ] [ore.internal.events.OSGiEventManager] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.ThingManager@3ce85427' takes more than 5000ms.
00:54:54.030 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_test changed from ON to OFF
00:54:59.632 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Statuscode is NOT OK: 404
00:54:59.641 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Session not found: ErrorResponse [code=2012, description=Session not found, messages=[SHC authentication denied.]]
00:54:59.643 [ERROR] [marthome.handler.InnogyBridgeHandler] - Unexcepted API error: Session not found
00:54:59.645 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:bridge:SMARTHOME09' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.645 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'SMARTHOME09'
00:54:59.652 [INFO ] [n.ollie.innogysmarthome.InnogyClient] - Uninitializing innogy SmartHome Session...
00:54:59.653 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:bd7ada6efe9b443b96ac87d36f877273 bridge status OFFLINE
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:6f417e8ad5a243e9a7751377d85ed811 bridge status OFFLINE
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.654 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSS:SMARTHOME09:5bfa78c42d884286a07d7a0803978ad4 bridge status OFFLINE
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:b02f0cf908c2402abb593f49878a2c18 bridge status OFFLINE
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:ce60af058193431e92fbb852dcb84a99 bridge status OFFLINE
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSS:SMARTHOME09:e20d69ab8dab40a482796eabe91983dc bridge status OFFLINE
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR): Session not found
00:54:59.655 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:PSS:SMARTHOME09:a630fcb0db7d4366813b5799bae09841 bridge status OFFLINE
00:54:59.665 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:17d65ecf38a141c58192803e72fc2e5e bridge status OFFLINE
00:54:59.676 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:bd7ada6efe9b443b96ac87d36f877273' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.677 [DEBUG] [marthome.handler.InnogyDeviceHandler] - initializeThing thing innogysmarthome:RST:SMARTHOME09:56e88db99dcc4ce8ac3ce3694449bc69 bridge status OFFLINE
00:54:59.678 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:17d65ecf38a141c58192803e72fc2e5e' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.681 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:b02f0cf908c2402abb593f49878a2c18' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.682 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:6f417e8ad5a243e9a7751377d85ed811' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.683 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:PSS:SMARTHOME09:e20d69ab8dab40a482796eabe91983dc' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.685 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:PSS:SMARTHOME09:5bfa78c42d884286a07d7a0803978ad4' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.686 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:56e88db99dcc4ce8ac3ce3694449bc69' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.690 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:RST:SMARTHOME09:ce60af058193431e92fbb852dcb84a99' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.691 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'innogysmarthome:PSS:SMARTHOME09:a630fcb0db7d4366813b5799bae09841' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
00:54:59.715 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Statuscode is NOT OK: 404
00:54:59.717 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Session not found: ErrorResponse [code=2012, description=Session not found, messages=[Session not found: a6d17a8138264fb4876f306fdf484b3b]]
00:54:59.718 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Session not found - ignoring
00:54:59.718 [INFO ] [marthome.handler.InnogyBridgeHandler] - innogy SmartHome bridge handler shut down.
00:54:59.720 [INFO ] [marthome.handler.InnogyBridgeHandler] - Scheduling reinitialize in 180 seconds.
Das ist ein innogy Fehler. Der Schaltbefehl wird ans innogy Backend geschickt und nach 10 Sekunden kommt ein 404 "Session not found" zurück. Dann wird das Binding runter gefahren und verbindet sich nach 3 Minuten neu. Die Zeit zum Wiederverbinden könnte etwas kürzer sein, aber ansonsten ist das Verhalten in Ordnung.
Im Endeffekt ist das der Mist mit dem Cloud-Backend. Wenn da etwas hakt, funktioniert's nicht. Der direkte SHC-Zugriff wäre robuster.