2017-01-20T07:00:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:01:25.226+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:01:25.226+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:05:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:10:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:11:25.236+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:11:25.236+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:15:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:20:00.066+0100 191 INFO [Healthcheck] DeadlockCheck did not find any deadlocks! 2017-01-20T07:20:00.066+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck will check bundles data folders for suspicious size. 2017-01-20T07:20:01.486+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck is finished. Going back to sleep. 2017-01-20T07:20:01.496+0100 191 DEBUG [Healthcheck] HomeMatic keys file has only one key entry as expected. 2017-01-20T07:20:01.576+0100 191 INFO [Healthcheck] Checking users... 2017-01-20T07:20:01.576+0100 191 DEBUG [Healthcheck] User name is: qhm@sengled.com 2017-01-20T07:20:01.576+0100 191 DEBUG [Healthcheck] User name is: admin 2017-01-20T07:20:01.576+0100 191 INFO [Healthcheck] All is good 2017-01-20T07:20:01.616+0100 191 INFO [Healthcheck] Check for users has finished. 2017-01-20T07:20:01.616+0100 191 INFO [Healthcheck] NotActiveBundlesCheck will check for not active bundles. 2017-01-20T07:20:01.616+0100 191 INFO [Healthcheck] NotActiveBundlesCheck finished. Didn't found not active bundles. 2017-01-20T07:20:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:21:25.246+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:21:25.246+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:25:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:30:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:31:25.256+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:31:25.256+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:35:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:40:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:41:25.266+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:41:25.266+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:45:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:50:00.046+0100 191 INFO [Healthcheck] DeadlockCheck did not find any deadlocks! 2017-01-20T07:50:00.066+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck will check bundles data folders for suspicious size. 2017-01-20T07:50:01.776+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck is finished. Going back to sleep. 2017-01-20T07:50:01.786+0100 191 DEBUG [Healthcheck] HomeMatic keys file has only one key entry as expected. 2017-01-20T07:50:01.876+0100 191 INFO [Healthcheck] Checking users... 2017-01-20T07:50:01.906+0100 191 DEBUG [Healthcheck] User name is: qhm@sengled.com 2017-01-20T07:50:01.906+0100 191 DEBUG [Healthcheck] User name is: admin 2017-01-20T07:50:01.926+0100 191 INFO [Healthcheck] All is good 2017-01-20T07:50:01.926+0100 191 INFO [Healthcheck] Check for users has finished. 2017-01-20T07:50:01.926+0100 191 INFO [Healthcheck] NotActiveBundlesCheck will check for not active bundles. 2017-01-20T07:50:01.926+0100 191 INFO [Healthcheck] NotActiveBundlesCheck finished. Didn't found not active bundles. 2017-01-20T07:50:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T07:51:25.276+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T07:51:25.276+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T07:55:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:00:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:01:25.286+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T08:01:25.286+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T08:05:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:08:11.206+0100 112 INFO [Cache.java:126][Cache::add] - uuid:74258a7a-bcf3-7425-8a7a-bcf300100058 successfully cached. 2017-01-20T08:08:12.016+0100 112 INFO [Cache.java:126][Cache::add] - uuid:060b7353-fca6-4070-85f4-1fbfb9add62c successfully cached. 2017-01-20T08:08:18.916+0100 112 INFO [Cache.java:126][Cache::add] - uuid:c85b760d5da20000_MR successfully cached. 2017-01-20T08:09:42.386+0100 167 WARNING [symbolicName=org.jupnp, className=org.jupnp.transport.spi.StreamClient] - HTTP request failed: (StreamRequestMessage) GET http://10.100.100.253:1900/l3f.xml (The target server failed to respond) 2017-01-20T08:09:42.846+0100 167 WARNING [symbolicName=org.jupnp, className=org.jupnp.transport.spi.StreamClient] - HTTP request failed: (StreamRequestMessage) GET http://10.100.100.253:1900/ipc.xml (The target server failed to respond) 2017-01-20T08:09:42.886+0100 112 INFO [Cache.java:126][Cache::add] - uuid:upnp-InternetGatewayDevice-BDDC39216137 successfully cached. 2017-01-20T08:09:43.396+0100 167 WARNING [symbolicName=org.jupnp, className=org.jupnp.transport.spi.StreamClient] - HTTP request failed: (StreamRequestMessage) GET http://10.100.100.9:2869/upnphost/udhisapi.dll?content=uuid:3f18d289-4d26-42c1-a410-75154f4a8b89 (Connection reset) 2017-01-20T08:09:43.776+0100 112 INFO [Cache.java:126][Cache::add] - uuid:3f18d289-4d26-42c1-a410-75154f4a8b89 successfully cached. 2017-01-20T08:10:09.846+0100 112 INFO [Cache.java:126][Cache::add] - uuid:12345678-9abc-def0-1234-50bd5f63c302 successfully cached. 2017-01-20T08:10:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:11:22.556+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:upnp-InternetGatewayDevice-BDDC39216137 removed from the cache. 2017-01-20T08:11:25.296+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T08:11:25.296+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T08:12:20.286+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:74258a7a-bcf3-7425-8a7a-bcf300100058 removed from the cache. 2017-01-20T08:13:18.926+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:060b7353-fca6-4070-85f4-1fbfb9add62c removed from the cache. 2017-01-20T08:13:38.476+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:c85b760d5da20000_MR removed from the cache. 2017-01-20T08:15:40.136+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexBridgeHandler] - Status update for flex light B0:CE:18:13:5E:33 detected. 2017-01-20T08:15:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:20:00.046+0100 191 INFO [Healthcheck] DeadlockCheck did not find any deadlocks! 2017-01-20T08:20:00.086+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck will check bundles data folders for suspicious size. 2017-01-20T08:20:01.636+0100 191 INFO [Healthcheck] SuspiciousDataFolderSizeCheck is finished. Going back to sleep. 2017-01-20T08:20:01.646+0100 191 DEBUG [Healthcheck] HomeMatic keys file has only one key entry as expected. 2017-01-20T08:20:01.736+0100 191 INFO [Healthcheck] Checking users... 2017-01-20T08:20:01.746+0100 191 DEBUG [Healthcheck] User name is: qhm@sengled.com 2017-01-20T08:20:01.746+0100 191 DEBUG [Healthcheck] User name is: admin 2017-01-20T08:20:01.746+0100 191 INFO [Healthcheck] All is good 2017-01-20T08:20:01.746+0100 191 INFO [Healthcheck] Check for users has finished. 2017-01-20T08:20:01.746+0100 191 INFO [Healthcheck] NotActiveBundlesCheck will check for not active bundles. 2017-01-20T08:20:01.766+0100 191 INFO [Healthcheck] NotActiveBundlesCheck finished. Didn't found not active bundles. 2017-01-20T08:20:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:21:25.306+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T08:21:25.306+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T08:24:43.676+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:3f18d289-4d26-42c1-a410-75154f4a8b89 removed from the cache. 2017-01-20T08:25:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:30:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:31:25.316+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T08:31:25.316+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T08:35:53.866+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:40:08.224+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Thing handler factory 'FlexHandlerFactory' removed 2017-01-20T08:40:08.384+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Thing handler for thing 'flex:flexBridge:SengLedflexBridge' removed. 2017-01-20T08:40:08.384+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Unassigning handler for thing 'flex:flexBridge:SengLedflexBridge' and setting status to UNINITIALIZED. 2017-01-20T08:40:08.434+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexBridge:SengLedflexBridge' updated: UNINITIALIZED (HANDLER_MISSING_ERROR) 2017-01-20T08:40:08.464+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Calling dispose handler for thing 'flex:flexBridge:SengLedflexBridge' at 'com.sengled.handler.FlexBridgeHandler@11a3d25'. 2017-01-20T08:40:08.474+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexBridge:SengLedflexBridge => CommunicationStatus{lastCommunicationSuccess=1484824237739, lastCommunicationFailure=null, offlineSince=null} 2017-01-20T08:40:08.544+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoChangedEvent] - 'flex:flexBridge:SengLedflexBridge' changed from ONLINE to UNINITIALIZED (HANDLER_MISSING_ERROR) 2017-01-20T08:40:08.624+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Thing handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' removed. 2017-01-20T08:40:08.624+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Unassigning handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' and setting status to UNINITIALIZED. 2017-01-20T08:40:08.634+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Calling dispose handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' at 'com.sengled.handler.FlexHandler@1ddd836'. 2017-01-20T08:40:08.634+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexHandler] - Handler disposes. Unregistering listener. 2017-01-20T08:40:08.644+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' updated: UNINITIALIZED (HANDLER_MISSING_ERROR) 2017-01-20T08:40:08.674+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexControl:SengLedflexBridge:B0CE18135E33 => CommunicationStatus{lastCommunicationSuccess=1484824255829, lastCommunicationFailure=1484824256669, offlineSince=null} 2017-01-20T08:40:08.734+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoChangedEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' changed from OFFLINE: Bridge reports light as not reachable to UNINITIALIZED (HANDLER_MISSING_ERROR) 2017-01-20T08:40:08.834+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Removing the XML related objects from module 'com.sengled'... 2017-01-20T08:40:08.834+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Removing the XML related objects from module 'com.sengled'... 2017-01-20T08:40:08.834+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Release the XmlDocumentProvider for the module 'com.sengled'. 2017-01-20T08:40:08.834+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Removing the XML related objects from module 'com.sengled'... 2017-01-20T08:40:08.834+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Release the XmlDocumentProvider for the module 'com.sengled'. 2017-01-20T08:40:08.904+0100 0 INFO Bundle with id #461 com.sengled, 1.0.0.qualifier was stopped. 2017-01-20T08:40:08.934+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.ProxyHandler#handlerEvent(): Received event=org.osgi.service.event.Event [topic=info/dmtree/DmtEvent/REPLACED] 2017-01-20T08:40:08.934+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.server.ProxyServerManager#sendDataToConnection: Sent data to ProxyServer[51080] 2017-01-20T08:40:08.954+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.server.ProxyServer#readStream(): Received data in ProxyServer[51080] 2017-01-20T08:40:08.954+0100 31 DEBUG CPEMethodsImpl.getParameterValues([Device.SoftwareModules.], showHidden: true) [START]: rootPath: Device.SoftwareModules. 2017-01-20T08:40:09.164+0100 163 DEBUG [symbolicName=com.qivicon.runtime.storage.db, className=com.qivicon.runtime.storage.db.DbStorageImpl] - Putting key flex:flexBridge:SengLedflexBridge in correspondence to value {"type":"com.qivicon.smarthome.config.device.CommunicationStatus","value":{"lastCommunicationSuccess":"1484824237739"}} 2017-01-20T08:40:09.204+0100 163 DEBUG [symbolicName=com.qivicon.runtime.storage.db, className=com.qivicon.runtime.storage.db.DbStorageImpl] - Putting key flex:flexControl:SengLedflexBridge:B0CE18135E33 in correspondence to value {"type":"com.qivicon.smarthome.config.device.CommunicationStatus","value":{"lastCommunicationSuccess":"1484824255829","lastCommunicationFailure":"1484824256669"}} 2017-01-20T08:40:09.204+0100 173 INFO The bundle 'com.sengled' has marked as removed with an event type [type=64]. 2017-01-20T08:40:09.204+0100 173 DEBUG The 'QiviconManifest.xml' for bundle com.sengled' has been unregistered. 2017-01-20T08:40:09.244+0100 173 DEBUG The listener 'com.qivicon.log.slf4j.impl.LogLevelManager@32ff39' is notified about one removed manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:09.244+0100 173 DEBUG The listener 'com.qivicon.oauth.clientregistry.internal.client.ClientInfoRegistryImpl@149ce62' is notified about one removed manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:09.244+0100 173 DEBUG The listener 'com.qivicon.remoteaccess.impl.RemoteAccessRegistryImpl@58cfe5' is notified about one removed manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:09.244+0100 199 DEBUG [symbolicName=com.qivicon.remoteaccess, className=com.qivicon.remoteaccess.impl.RemoteAccessRegistryImpl] - Removing permissions for application: com.sengled 2017-01-20T08:40:09.244+0100 173 DEBUG The listener 'com.qivicon.remoteaccess.impl.RemoteAccessControlContextHandler@1d335f2' is notified about one removed manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:09.244+0100 0 INFO Bundle with id #461 com.sengled, 1.0.0.qualifier was updated. 2017-01-20T08:40:09.474+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.ProxyListener#autonomousDUStateChangeComplete(): Received AutonomousDUStateChangeComplete. 2017-01-20T08:40:10.354+0100 173 DEBUG The 'QiviconManifest.xml' for bundle 'com.sengled' has been parsed successfully. 2017-01-20T08:40:10.354+0100 173 INFO The 'QiviconManifest.xml' for bundle com.sengled' has been registered. 2017-01-20T08:40:10.354+0100 173 DEBUG The listener 'com.qivicon.log.slf4j.impl.LogLevelManager@32ff39' is notified about one added manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:10.394+0100 173 DEBUG The listener 'com.qivicon.oauth.clientregistry.internal.client.ClientInfoRegistryImpl@149ce62' is notified about one added manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:10.394+0100 173 DEBUG The listener 'com.qivicon.remoteaccess.impl.RemoteAccessRegistryImpl@58cfe5' is notified about one added manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:10.394+0100 173 DEBUG The listener 'com.qivicon.remoteaccess.impl.RemoteAccessControlContextHandler@1d335f2' is notified about one added manifest entry for the bundle 'com.sengled'... 2017-01-20T08:40:10.394+0100 199 INFO [symbolicName=com.qivicon.remoteaccess, className=com.qivicon.remoteaccess.impl.RemoteAccessControlContextHandler] - Registering remote ACC for bundle 'com.sengled' with security id 'com.sengled.remote'. 2017-01-20T08:40:10.604+0100 0 INFO Bundle with id #461 com.sengled, 1.0.0.qualifier was started. 2017-01-20T08:40:10.844+0100 112 INFO [Cache.java:178][Cache::removeRootDevice] - uuid:12345678-9abc-def0-1234-50bd5f63c302 removed from the cache. 2017-01-20T08:40:13.684+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Thing handler factory 'FlexHandlerFactory' added 2017-01-20T08:40:13.714+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Calling 'FlexHandlerFactory.registerHandler()' for thing 'flex:flexBridge:SengLedflexBridge'. 2017-01-20T08:40:13.854+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexBridgeHandler] - 00000000000000000 2017-01-20T08:40:13.854+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexBridgeHandler] - 222222222222222 2017-01-20T08:40:13.854+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexBridgeHandler] - 3333333333333333 2017-01-20T08:40:13.884+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexBridgeHandler] - bb55555 2017-01-20T08:40:13.944+0100 157 ERROR [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Exception occured while calling thing handler factory 'com.sengled.internal.FlexHandlerFactory@10e576e': java.lang.NoClassDefFoundError: org/osgi/framework/BundleContext java.util.concurrent.ExecutionException: java.lang.NoClassDefFoundError: org/osgi/framework/BundleContext at java.util.concurrent.FutureTask.report(FutureTask.java:122) at java.util.concurrent.FutureTask.get(FutureTask.java:206) at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:186) at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:79) at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:63) at org.eclipse.smarthome.core.thing.internal.ThingManager.registerHandler(ThingManager.java:633) at org.eclipse.smarthome.core.thing.internal.ThingManager.addThingHandlerFactory(ThingManager.java:1021) at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.prosyst.mbs.impl.services.scm.model.MethodInvoker.invoke(MethodInvoker.java:44) at com.prosyst.mbs.impl.services.scm.model.ComponentReference.bind(ComponentReference.java:387) at com.prosyst.mbs.impl.services.scm.model.ServiceComponentProp.bindReference(ServiceComponentProp.java:482) at com.prosyst.mbs.impl.services.scm.InstanceProcess.dynamicBind(InstanceProcess.java:427) at com.prosyst.mbs.impl.services.scm.Resolver.getEligible(Resolver.java:343) at com.prosyst.mbs.impl.services.scm.SCRManager.serviceChanged(SCRManager.java:267) at com.prosyst.mbs.impl.framework.module.security.BasicPEA.dispatch(BasicSecurityImpl.java:554) at com.prosyst.mbs.framework.security.AbstractPEA.run(AbstractPEA.java:153) at java.security.AccessController.doPrivileged(Native Method) at com.prosyst.mbs.framework.security.AbstractPEA.doPrivileged(AbstractPEA.java:42) at com.prosyst.mbs.impl.framework.module.security.BasicSecurityImpl.doPrivileged(BasicSecurityImpl.java:306) at com.prosyst.mbs.impl.framework.EventsManager.serviceChanged(EventsManager.java:1111) at com.prosyst.mbs.impl.framework.BundleContextImpl.registerService(BundleContextImpl.java:686) at com.prosyst.mbs.impl.framework.BundleContextImpl.registerService(BundleContextImpl.java:645) at com.prosyst.mbs.impl.services.scm.InstanceProcess.registerService(InstanceProcess.java:542) at com.prosyst.mbs.impl.services.scm.InstanceProcess.buildComponents(InstanceProcess.java:204) at com.prosyst.mbs.impl.services.scm.Resolver.buildNewlySatisfied(Resolver.java:478) at com.prosyst.mbs.impl.services.scm.Resolver.enableComponents(Resolver.java:226) at com.prosyst.mbs.impl.services.scm.SCRManager.performWork(SCRManager.java:876) at com.prosyst.mbs.impl.services.scm.SCRManager$QueuedJob.dispatch(SCRManager.java:846) at com.prosyst.mbs.impl.services.scm.WorkThread.run(WorkThread.java:82) at com.prosyst.util.impl.tpt.threadpool.PEA.run(PEA.java:11) at java.security.AccessController.doPrivileged(Native Method) at com.prosyst.util.impl.tpt.threadpool.ExecutorImpl.run(ExecutorImpl.java:186) Caused by: java.lang.NoClassDefFoundError: org/osgi/framework/BundleContext at com.sengled.internal.FlexHandlerFactory.registerLightDiscoveryService(FlexHandlerFactory.java:130) at com.sengled.internal.FlexHandlerFactory.createHandler(FlexHandlerFactory.java:110) at org.eclipse.smarthome.core.thing.binding.BaseThingHandlerFactory.registerHandler(BaseThingHandlerFactory.java:133) at org.eclipse.smarthome.core.thing.internal.ThingManager$7.call(ThingManager.java:637) at org.eclipse.smarthome.core.thing.internal.ThingManager$7.call(ThingManager.java:1) at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:177) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.ClassNotFoundException: org.osgi.framework.BundleContext, from bundle com.sengled at com.prosyst.mbs.impl.framework.DefaultClassProvider.loadClass_(DefaultClassProvider.java:655) at com.prosyst.mbs.impl.framework.DefaultClassProvider.loadClass(DefaultClassProvider.java:488) at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ... 10 more 2017-01-20T08:40:13.954+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexBridge:SengLedflexBridge' updated: UNINITIALIZED (HANDLER_REGISTERING_ERROR): org/osgi/framework/BundleContext 2017-01-20T08:40:13.994+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexBridge:SengLedflexBridge => CommunicationStatus{lastCommunicationSuccess=1484824237739, lastCommunicationFailure=null, offlineSince=null} 2017-01-20T08:40:14.034+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Calling 'FlexHandlerFactory.registerHandler()' for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33'. 2017-01-20T08:40:14.094+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoChangedEvent] - 'flex:flexBridge:SengLedflexBridge' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to UNINITIALIZED (HANDLER_REGISTERING_ERROR): org/osgi/framework/BundleContext 2017-01-20T08:40:14.264+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Thing handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' added. 2017-01-20T08:40:14.264+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Assigning handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33'. 2017-01-20T08:40:14.364+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Meta-data of bundle 'com.sengled' is not fully loaded ([org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker$1@f0d6e4(/ESH-INF/thing/), org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker$1@15bb9b4(/ESH-INF/binding/)]), deferring handler initialization for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' 2017-01-20T08:40:14.994+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Reading the XML document '/ESH-INF/binding/binding.xml' in module 'com.sengled'... 2017-01-20T08:40:15.034+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Reading the XML document '/ESH-INF/thing/bridge.xml' in module 'com.sengled'... 2017-01-20T08:40:15.054+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Create an empty XmlDocumentProvider for the module 'com.sengled'. 2017-01-20T08:40:15.054+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - 'com.sengled' still vetoed by '[org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker$1@f0d6e4(/ESH-INF/thing/)]' 2017-01-20T08:40:15.054+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - 'com.sengled' queued '[com.sengled.handler.FlexHandler@53647]' 2017-01-20T08:40:15.204+0100 163 DEBUG [symbolicName=com.qivicon.runtime.storage.db, className=com.qivicon.runtime.storage.db.DbStorageImpl] - Putting key flex:flexBridge:SengLedflexBridge in correspondence to value {"type":"com.qivicon.smarthome.config.device.CommunicationStatus","value":{"lastCommunicationSuccess":"1484824237739"}} 2017-01-20T08:40:15.244+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Create an empty XmlDocumentProvider for the module 'com.sengled'. 2017-01-20T08:40:15.294+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Reading the XML document '/ESH-INF/thing/channels.xml' in module 'com.sengled'... 2017-01-20T08:40:15.464+0100 160 DEBUG [symbolicName=org.eclipse.smarthome.config.xml, className=org.eclipse.smarthome.config.xml.osgi.XmlDocumentBundleTracker] - Reading the XML document '/ESH-INF/thing/flex.xml' in module 'com.sengled'... 2017-01-20T08:40:15.844+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Finished loading meta-data of bundle 'com.sengled' 2017-01-20T08:40:16.134+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' updated: INITIALIZING 2017-01-20T08:40:16.134+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - All data has been loaded, going to initialize 'flex:flexControl:SengLedflexBridge:B0CE18135E33'. 2017-01-20T08:40:16.134+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.internal.ThingManager] - Calling initialize handler for thing 'flex:flexControl:SengLedflexBridge:B0CE18135E33' at 'com.sengled.handler.FlexHandler@53647'. 2017-01-20T08:40:16.164+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexHandler] - Initializing flex light handler. 2017-01-20T08:40:16.164+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexHandler] - initializeThing thing flex:flexControl:SengLedflexBridge:B0CE18135E33 bridge status UNINITIALIZED 2017-01-20T08:40:16.174+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexControl:SengLedflexBridge:B0CE18135E33 => CommunicationStatus{lastCommunicationSuccess=1484824255829, lastCommunicationFailure=1484824256669, offlineSince=null} 2017-01-20T08:40:16.254+0100 461 DEBUG [symbolicName=com.sengled, className=com.sengled.handler.FlexHandler] - initializeThing thing flex:flexControl:SengLedflexBridge:B0CE18135E33 bridge status ONLINE 2017-01-20T08:40:16.354+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoChangedEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING 2017-01-20T08:40:16.444+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' updated: OFFLINE 2017-01-20T08:40:16.444+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexControl:SengLedflexBridge:B0CE18135E33 => CommunicationStatus{lastCommunicationSuccess=1484824255829, lastCommunicationFailure=1484898016444, offlineSince=1484898016444} 2017-01-20T08:40:16.524+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoChangedEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' changed from INITIALIZING to OFFLINE 2017-01-20T08:40:16.554+0100 157 DEBUG [symbolicName=org.eclipse.smarthome.core.thing, className=org.eclipse.smarthome.core.thing.events.ThingStatusInfoEvent] - 'flex:flexControl:SengLedflexBridge:B0CE18135E33' updated: OFFLINE 2017-01-20T08:40:16.554+0100 255 DEBUG [symbolicName=com.qivicon.smarthome.config.device, className=com.qivicon.smarthome.config.device.internal.DeviceStatusManagerImpl] - Device status updated flex:flexControl:SengLedflexBridge:B0CE18135E33 => CommunicationStatus{lastCommunicationSuccess=1484824255829, lastCommunicationFailure=1484898016554, offlineSince=1484898016444} 2017-01-20T08:40:17.214+0100 163 DEBUG [symbolicName=com.qivicon.runtime.storage.db, className=com.qivicon.runtime.storage.db.DbStorageImpl] - Putting key flex:flexControl:SengLedflexBridge:B0CE18135E33 in correspondence to value {"type":"com.qivicon.smarthome.config.device.CommunicationStatus","value":{"lastCommunicationSuccess":"1484824255829","lastCommunicationFailure":"1484898016554","offlineSince":"1484898016444"}} 2017-01-20T08:40:21.664+0100 31 DEBUG CPEMethodsImpl.getParameterValues([Device.SoftwareModules.], showHidden: true) [END]: Total time : 12710ms. 2017-01-20T08:40:21.744+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.DUStateCache#getDUState(): get new state 2017-01-20T08:40:21.854+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.server.ProxyServerManager#sendDataToConnection: Sent data to ProxyServer[51080] 2017-01-20T08:40:21.854+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.ProxyHandler#handlerEvent(): Received event=org.osgi.service.event.Event [topic=info/dmtree/DmtEvent/REPLACED] 2017-01-20T08:40:21.854+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.server.ProxyServerManager#sendDataToConnection: Sent data to ProxyServer[51080] 2017-01-20T08:40:27.434+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.server.ProxyServer#readStream(): Received data in ProxyServer[51080] 2017-01-20T08:40:27.464+0100 31 DEBUG CPEMethodsImpl.getParameterValues([Device.SoftwareModules.], showHidden: true) [START]: rootPath: Device.SoftwareModules. 2017-01-20T08:40:33.184+0100 31 DEBUG CPEMethodsImpl.getParameterValues([Device.SoftwareModules.], showHidden: true) [END]: Total time : 5720ms. 2017-01-20T08:40:33.194+0100 62 INFO com.sei_networks.sgw.tr069proxy.impl.DUStateCache#getDUState(): get new state 2017-01-20T08:40:49.554+0100 198 DEBUG [symbolicName=com.qivicon.oauth.service, className=com.qivicon.oauth.impl.service.token.OAuthTokenGenerator] - Serialized Token: eyJhbGciOiJIUzI1NiJ9.eyJpc3MiOiJhMjBhNmY5ZTYwM2E1ZDk2NzY5MzM1YmMzNTI5YjE5MyIsImF1ZCI6IlJFTU9URVNFUlZJQ0UiLCJpYXQiOjE0ODQ4OTgwNDksImV4cCI6MTQ4NDkyNjg0OSwianRpIjoiNWI1YjdiMWYtZWMyMC00NzQ5LWJmYjMtZWQ2NTM3Y2I2ZDYxIiwiaHR0cDovL3Fpdmljb24uY29tL2F4c2NoZW1hL2FwcGxpY2F0aW9uaWQiOiJzeXN0ZW0uYXBwLndlYmNvbnNvbGUifQ.hPMSsEpgZGNDoQTFnWb8nGZvmMLeeWZX7SxO5RFpBnQ 2017-01-20T08:40:53.864+0100 261 DEBUG [symbolicName=com.qivicon.smarthome.config.system, className=com.qivicon.smarthome.config.system.internal.SystemManagerImpl] - Refreshing system model 2017-01-20T08:41:25.324+0100 104 INFO DriverOpScheduler: Woken up. 2017-01-20T08:41:25.324+0100 104 INFO DriverOpScheduler: Asleep. 2017-01-20T08:41:30.134+0100 220 WARNING 404 for resource /favicon.ico ]