==> /var/log/openhab/openhab.log <==
2023-01-16 14:40:44.369 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Amsterdam'.
2023-01-16 14:40:44.423 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to '53.1856896837345,5.8343732357025155'.
2023-01-16 14:40:44.425 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'nl_NL'.
2023-01-16 14:40:49.762 [WARN ] [.transport.servlet.ServletController] - Can't find the request for http://192.168.0.68:8080/rest/events/states's Observer 
2023-01-16 14:41:00.545 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2023-01-16 14:41:07.046 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : BundleComponentActivator : ComponentHolder created.
2023-01-16 14:41:07.069 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2023-01-16 14:41:07.101 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ConfigDescriptionRegistryinterface=org.openhab.core.config.core.ConfigDescriptionRegistry, filter=null, policy=static, cardinality=1..1, bind=setConfigDescriptionRegistry, unbind=unsetConfigDescriptionRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-01-16 14:41:07.104 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingRegistryinterface=org.openhab.core.thing.ThingRegistry, filter=null, policy=static, cardinality=1..1, bind=setThingRegistry, unbind=unsetThingRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-01-16 14:41:07.107 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Dependency Manager created ThingTypeRegistryinterface=org.openhab.core.thing.type.ThingTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=setThingTypeRegistry, unbind=unsetThingTypeRegistry, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-01-16 14:41:07.110 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component created: DS=DS13, implementation=org.openhab.binding.zwave.internal.ZWaveConfigProvider, immediate=true, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zwave.internal.ZWaveConfigProvider]
2023-01-16 14:41:07.113 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Services: scope=singleton, services=[org.openhab.core.config.core.ConfigDescriptionProvider, org.openhab.core.config.core.ConfigOptionProvider]
2023-01-16 14:41:07.148 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component Properties: {}
2023-01-16 14:41:07.150 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2023-01-16 14:41:07.152 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2023-01-16 14:41:07.153 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Component can not be activated since it is in state disabled
2023-01-16 14:41:07.155 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider] : Querying state disabled
2023-01-16 14:41:07.156 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Updating target filters
2023-01-16 14:41:07.158 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : No change in target property for dependency ConfigDescriptionRegistry: currently registered: false
2023-01-16 14:41:07.181 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] :  No existing service listener to unregister for dependency ConfigDescriptionRegistry
2023-01-16 14:41:07.183 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Setting target property for dependency ConfigDescriptionRegistry to null
2023-01-16 14:41:07.209 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : New service tracker for ConfigDescriptionRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.config.core.ConfigDescriptionRegistry), initialReferenceFilter (objectClass=org.openhab.core.config.core.ConfigDescriptionRegistry)
2023-01-16 14:41:07.210 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ConfigDescriptionRegistry tracker reset (closed)
2023-01-16 14:41:07.217 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=237, service.bundleid=161, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=107} (enter)
2023-01-16 14:41:07.220 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-01-16 14:41:07.277 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ConfigDescriptionRegistry tracking 1 SingleStatic added {org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=237, service.bundleid=161, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=107} (exit)
2023-01-16 14:41:07.280 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ConfigDescriptionRegistry tracker opened
2023-01-16 14:41:07.282 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : registering service listener for dependency ConfigDescriptionRegistry
2023-01-16 14:41:07.285 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : No change in target property for dependency ThingRegistry: currently registered: false
2023-01-16 14:41:07.288 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] :  No existing service listener to unregister for dependency ThingRegistry
2023-01-16 14:41:07.291 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Setting target property for dependency ThingRegistry to null
2023-01-16 14:41:07.294 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : New service tracker for ThingRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.ThingRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.ThingRegistry)
2023-01-16 14:41:07.297 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingRegistry tracker reset (closed)
2023-01-16 14:41:07.302 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=374, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=244} (enter)
2023-01-16 14:41:07.305 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingRegistry tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-01-16 14:41:07.308 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingRegistry tracking 2 SingleStatic added {org.openhab.core.thing.ThingRegistry}={service.id=374, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=244} (exit)
2023-01-16 14:41:07.311 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingRegistry tracker opened
2023-01-16 14:41:07.314 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : registering service listener for dependency ThingRegistry
2023-01-16 14:41:07.348 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : No change in target property for dependency ThingTypeRegistry: currently registered: false
2023-01-16 14:41:07.352 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] :  No existing service listener to unregister for dependency ThingTypeRegistry
2023-01-16 14:41:07.354 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Setting target property for dependency ThingTypeRegistry to null
2023-01-16 14:41:07.360 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : New service tracker for ThingTypeRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ThingTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ThingTypeRegistry)
2023-01-16 14:41:07.365 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingTypeRegistry tracker reset (closed)
2023-01-16 14:41:07.375 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=259} (enter)
2023-01-16 14:41:07.377 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingTypeRegistry tracking 3 SingleStatic active: false trackerOpened: false optional: false
2023-01-16 14:41:07.384 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingTypeRegistry tracking 3 SingleStatic added {org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=259} (exit)
2023-01-16 14:41:07.385 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : dm ThingTypeRegistry tracker opened
2023-01-16 14:41:07.387 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : registering service listener for dependency ThingTypeRegistry
2023-01-16 14:41:07.389 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Changed state from disabled to unsatisfiedReference
2023-01-16 14:41:07.391 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Component enabled
2023-01-16 14:41:07.393 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : ActivateInternal
2023-01-16 14:41:07.395 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.397 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.399 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Activating component from state unsatisfiedReference
2023-01-16 14:41:07.401 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.411 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.417 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Changed state from unsatisfiedReference to satisfied
2023-01-16 14:41:07.425 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : registration change queue [registered]
2023-01-16 14:41:07.442 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Checking constructor public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
2023-01-16 14:41:07.446 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Found constructor with 0 arguments : public org.openhab.binding.zwave.internal.ZWaveConfigProvider()
2023-01-16 14:41:07.470 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getting bind: setConfigDescriptionRegistry
2023-01-16 14:41:07.472 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Locating method setConfigDescriptionRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.473 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry
2023-01-16 14:41:07.509 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.framework.ServiceReference]) not found
2023-01-16 14:41:07.512 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-01-16 14:41:07.514 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getReferenceClass: Looking for interface class org.openhab.core.config.core.ConfigDescriptionRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.524 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getParameterClass: Found class org.openhab.core.config.core.ConfigDescriptionRegistry
2023-01-16 14:41:07.525 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.config.core.ConfigDescriptionRegistry
2023-01-16 14:41:07.528 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
2023-01-16 14:41:07.530 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setConfigDescriptionRegistry(org.openhab.core.config.core.ConfigDescriptionRegistry)
2023-01-16 14:41:07.532 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getting bind: setThingRegistry
2023-01-16 14:41:07.542 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Locating method setThingRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.543 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry
2023-01-16 14:41:07.545 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.framework.ServiceReference]) not found
2023-01-16 14:41:07.559 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-01-16 14:41:07.570 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getReferenceClass: Looking for interface class org.openhab.core.thing.ThingRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.571 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getParameterClass: Found class org.openhab.core.thing.ThingRegistry
2023-01-16 14:41:07.583 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.ThingRegistry
2023-01-16 14:41:07.584 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing.ThingRegistry)
2023-01-16 14:41:07.596 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingRegistry(org.openhab.core.thing.ThingRegistry)
2023-01-16 14:41:07.597 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getting bind: setThingTypeRegistry
2023-01-16 14:41:07.599 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Locating method setThingTypeRegistry in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.601 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry
2023-01-16 14:41:07.611 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.framework.ServiceReference]) not found
2023-01-16 14:41:07.613 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-01-16 14:41:07.615 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ThingTypeRegistry through loader of org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.617 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getParameterClass: Found class org.openhab.core.thing.type.ThingTypeRegistry
2023-01-16 14:41:07.619 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.thing.type.ThingTypeRegistry
2023-01-16 14:41:07.620 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
2023-01-16 14:41:07.624 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveConfigProvider.setThingTypeRegistry(org.openhab.core.thing.type.ThingTypeRegistry)
2023-01-16 14:41:07.639 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : This thread collected dependencies
2023-01-16 14:41:07.641 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getService (single component manager) dependencies collected.
2023-01-16 14:41:07.642 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state satisfied
2023-01-16 14:41:07.644 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Querying state satisfied
2023-01-16 14:41:07.647 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : For dependency ConfigDescriptionRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.config.core.ConfigDescriptionRegistry}={service.id=237, service.bundleid=161, service.scope=bundle, component.name=org.openhab.core.config.core.ConfigDescriptionRegistry, component.id=107}] service: [org.openhab.core.config.core.ConfigDescriptionRegistry@1dca222]]]
2023-01-16 14:41:07.650 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : For dependency ThingRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.ThingRegistry}={service.id=374, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingRegistryImpl, component.id=244}] service: [org.openhab.core.thing.internal.ThingRegistryImpl@e5d688]]]
2023-01-16 14:41:07.652 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : For dependency ThingTypeRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ThingTypeRegistry}={service.id=411, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ThingTypeRegistry, component.id=259}] service: [org.openhab.core.thing.type.ThingTypeRegistry@9997c8]]]
2023-01-16 14:41:07.824 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoking bind: setConfigDescriptionRegistry: parameters [org.openhab.core.config.core.ConfigDescriptionRegistry]
2023-01-16 14:41:07.826 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoked bind: setConfigDescriptionRegistry
2023-01-16 14:41:07.828 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoking bind: setThingRegistry: parameters [org.openhab.core.thing.internal.ThingRegistryImpl]
2023-01-16 14:41:07.830 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoked bind: setThingRegistry
2023-01-16 14:41:07.832 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoking bind: setThingTypeRegistry: parameters [org.openhab.core.thing.type.ThingTypeRegistry]
2023-01-16 14:41:07.834 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : invoked bind: setThingTypeRegistry
2023-01-16 14:41:07.836 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : getting activate: activate
2023-01-16 14:41:07.838 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Locating method activate in class org.openhab.binding.zwave.internal.ZWaveConfigProvider
2023-01-16 14:41:07.840 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method org.openhab.binding.zwave.internal.ZWaveConfigProvider.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-16 14:41:07.841 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Locating method activate in class java.lang.Object
2023-01-16 14:41:07.843 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-16 14:41:07.845 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : activate method [activate] not found, ignoring
2023-01-16 14:41:07.847 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Set implementation object for component
2023-01-16 14:41:07.848 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveConfigProvider(304)] : Changed state from satisfied to active
2023-01-16 14:41:07.851 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Dependency Manager created SerialPortManagerinterface=org.openhab.core.io.transport.serial.SerialPortManager, filter=null, policy=static, cardinality=1..1, bind=setSerialPortManager, unbind=unsetSerialPortManager, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-01-16 14:41:07.852 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component created: DS=DS13, implementation=org.openhab.binding.zwave.internal.ZWaveHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zwave.internal.ZWaveHandlerFactory]
2023-01-16 14:41:07.854 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2023-01-16 14:41:07.856 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component Properties: {}
2023-01-16 14:41:07.857 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
2023-01-16 14:41:07.859 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
2023-01-16 14:41:07.861 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Component can not be activated since it is in state disabled
2023-01-16 14:41:07.863 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory] : Querying state disabled
2023-01-16 14:41:07.864 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Updating target filters
2023-01-16 14:41:07.866 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : No change in target property for dependency SerialPortManager: currently registered: false
2023-01-16 14:41:07.868 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] :  No existing service listener to unregister for dependency SerialPortManager
2023-01-16 14:41:07.870 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Setting target property for dependency SerialPortManager to null
2023-01-16 14:41:07.872 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : New service tracker for SerialPortManager, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.serial.SerialPortManager), initialReferenceFilter (objectClass=org.openhab.core.io.transport.serial.SerialPortManager)
2023-01-16 14:41:07.874 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : dm SerialPortManager tracker reset (closed)
2023-01-16 14:41:07.877 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : dm SerialPortManager tracker opened
2023-01-16 14:41:07.879 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : registering service listener for dependency SerialPortManager
2023-01-16 14:41:07.881 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Changed state from disabled to unsatisfiedReference
2023-01-16 14:41:07.883 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Component enabled
2023-01-16 14:41:07.885 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : ActivateInternal
2023-01-16 14:41:07.887 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.889 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.891 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Activating component from state unsatisfiedReference
2023-01-16 14:41:07.893 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.895 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:07.897 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Dependency not satisfied: SerialPortManager
2023-01-16 14:41:07.899 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Not all dependencies satisfied, cannot activate
2023-01-16 14:41:08.939 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.0.68' with clientid 3b6fb76d-8969-41db-9120-f6ce49838947
2023-01-16 14:41:09.232 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : dm SerialPortManager tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=496, service.bundleid=276, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=312} (enter)
2023-01-16 14:41:09.234 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : ActivateInternal
2023-01-16 14:41:09.236 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:09.237 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:09.239 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Activating component from state unsatisfiedReference
2023-01-16 14:41:09.240 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:09.242 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state unsatisfiedReference
2023-01-16 14:41:09.244 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Changed state from unsatisfiedReference to satisfied
2023-01-16 14:41:09.245 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : registration change queue [registered]
2023-01-16 14:41:09.256 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Checking constructor public org.openhab.binding.zwave.internal.ZWaveHandlerFactory()
2023-01-16 14:41:09.257 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Found constructor with 0 arguments : public org.openhab.binding.zwave.internal.ZWaveHandlerFactory()
2023-01-16 14:41:09.259 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : getting bind: setSerialPortManager
2023-01-16 14:41:09.261 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Locating method setSerialPortManager in class org.openhab.binding.zwave.internal.ZWaveHandlerFactory
2023-01-16 14:41:09.263 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : doFindMethod: Looking for method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager
2023-01-16 14:41:09.265 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager([interface org.osgi.framework.ServiceReference]) not found
2023-01-16 14:41:09.267 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-01-16 14:41:09.272 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : getReferenceClass: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.zwave.internal.ZWaveHandlerFactory
2023-01-16 14:41:09.274 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : getParameterClass: Found class org.openhab.core.io.transport.serial.SerialPortManager
2023-01-16 14:41:09.276 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.io.transport.serial.SerialPortManager
2023-01-16 14:41:09.278 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : doFindMethod: Found Method protected void org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
2023-01-16 14:41:09.279 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Found bind method: protected void org.openhab.binding.zwave.internal.ZWaveHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
2023-01-16 14:41:09.282 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : This thread collected dependencies
2023-01-16 14:41:09.283 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : getService (single component manager) dependencies collected.
2023-01-16 14:41:09.285 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state satisfied
2023-01-16 14:41:09.287 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Querying state satisfied
2023-01-16 14:41:09.289 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : For dependency SerialPortManager, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=496, service.bundleid=276, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=312}] service: [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl@1f205ea]]]
2023-01-16 14:41:09.293 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : invoking bind: setSerialPortManager: parameters [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl]
2023-01-16 14:41:09.295 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : invoked bind: setSerialPortManager
2023-01-16 14:41:09.297 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : getting activate: activate
2023-01-16 14:41:09.299 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Locating method activate in class org.openhab.binding.zwave.internal.ZWaveHandlerFactory
2023-01-16 14:41:09.301 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Declared Method org.openhab.binding.zwave.internal.ZWaveHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-16 14:41:09.303 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2023-01-16 14:41:09.305 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2023-01-16 14:41:09.307 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2023-01-16 14:41:09.314 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : invoked activate: activate
2023-01-16 14:41:09.315 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Set implementation object for component
2023-01-16 14:41:09.317 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : Changed state from satisfied to active
2023-01-16 14:41:09.325 [DEBUG] [g.zwave.internal.ZWaveHandlerFactory] - bundle org.openhab.binding.zwave:3.4.1 (269)[org.openhab.binding.zwave.internal.ZWaveHandlerFactory(305)] : dm SerialPortManager tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=496, service.bundleid=276, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=312} (exit)
2023-01-16 14:41:09.857 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:10.811 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_SonosArc_CurrentAlbumCoverArt' changed from NULL to raw type (image/png): 27882 bytes
2023-01-16 14:41:11.025 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Eettafel_Dimmer' changed from NULL to 0.0
2023-01-16 14:41:11.056 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Constanza_Lamp_Dimmer' changed from NULL to 0.0
2023-01-16 14:41:11.072 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Uplighter_Lamp_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:11.120 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hlk_Deurbel_Schakelaar' changed from NULL to 1 s
2023-01-16 14:41:11.123 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'LuxaflexTuin_Shutter' changed from NULL to 0.0
2023-01-16 14:41:11.229 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Leeslamp_Rechts_Schakelaar' changed from NULL to OFF
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:11.493 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state raw type (image/png): 27882 bytes (StringType) on item Wrk_SonosConnectAmp_CurrentAlbumCoverArt of type ImageItem, ignoring it
==> /var/log/openhab/events.log <==
2023-01-16 14:41:11.514 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Leeslamp_Links_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:11.533 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestPrecipitationTotal' changed from NULL to 0 ?
2023-01-16 14:41:11.552 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveNode010FGWP102MeteredWallPlugSwitch_Switch' changed from NULL to OFF
2023-01-16 14:41:11.606 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_ZoneName' changed from NULL to Slaapkamer
2023-01-16 14:41:11.672 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Kdk_Lampen_Dimmer' changed from NULL to 0.0
2023-01-16 14:41:11.712 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveNode015ZWA003NanoMoteAnne_SceneNumber' changed from NULL to 4.0
2023-01-16 14:41:11.911 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_Windrichting' changed from NULL to N
2023-01-16 14:41:11.959 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_MediaTitle' changed from NULL to Baby Makes Her Blue Jeans Talk
2023-01-16 14:41:11.979 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Ba_Buitenlamp_Zij_Schakelaar' changed from NULL to ON
2023-01-16 14:41:12.071 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Temperatuur_Temperatuur' changed from NULL to 20.1
2023-01-16 14:41:12.092 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_MediaTitle' changed from NULL to NPO Radio 2
2023-01-16 14:41:12.108 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Luxaflex_Tuin' changed from NULL to 59.0
2023-01-16 14:41:12.186 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bv_Buitenlampen_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:12.229 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_CurrentAlbum' changed from NULL to 
2023-01-16 14:41:12.301 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_Energie' changed from NULL to 1478012 kWh
2023-01-16 14:41:12.314 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveNode014AD147ZWaveDimmerPlug_Dimmer' changed from NULL to 0.0
2023-01-16 14:41:12.444 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_Temperatuur_Temperatuur' changed from NULL to 21.6
2023-01-16 14:41:12.453 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_Mute' changed from NULL to OFF
2023-01-16 14:41:12.474 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaamWaskamer_Positie' changed from NULL to 0.0
2023-01-16 14:41:12.490 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bkk_Plafondlamp_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:12.542 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_LaatstGezien' changed from NULL to 2022-08-01T13:13:30.000+0200
2023-01-16 14:41:12.562 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Constanza_Lamp_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:12.593 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Ba_Buiten_Bibl_Switch' changed from NULL to OFF
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:12.611 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state PLAY (StringType) on item Wrk_SonosConnectAmp_MediaControl of type PlayerItem, ignoring it
==> /var/log/openhab/events.log <==
2023-01-16 14:41:12.642 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestPrecipitationRate' changed from NULL to 0 ?
2023-01-16 14:41:12.666 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_Temperatuur_Luchtvochtigheid' changed from NULL to 38.0
2023-01-16 14:41:12.680 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hlk_Deurbel_Contact' changed from NULL to OPEN
2023-01-16 14:41:12.727 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_TotaleOpbrengst' changed from NULL to 13 W
2023-01-16 14:41:12.771 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_WinddelenOpbrengst' changed from NULL to 24 W
2023-01-16 14:41:12.792 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_InBedrijfPercentage' changed from NULL to -50.63209068992413
2023-01-16 14:41:12.817 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_Volume' changed from NULL to 12.0
2023-01-16 14:41:12.855 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_Windsnelheid' changed from NULL to 3.0
2023-01-16 14:41:12.880 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_MediaArtist' changed from NULL to Ray Sawyer
2023-01-16 14:41:12.918 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Trapspotjes_Dimniveau' changed from NULL to 0.0
2023-01-16 14:41:12.932 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Kerstverlichting' changed from NULL to ON
2023-01-16 14:41:12.946 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RolgordijnKleedkamer_Positie' changed from NULL to 0.0
2023-01-16 14:41:12.963 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Ba_Buitenlamp_Zij_DimNiveau' changed from NULL to 0.0
2023-01-16 14:41:12.976 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_ZoneName' changed from NULL to Werkkamer
2023-01-16 14:41:13.007 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Temperatuur_Luchtvochtigheid' changed from NULL to 55.0
2023-01-16 14:41:13.034 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestPressure' changed from NULL to 977.6502156 hPa
2023-01-16 14:41:13.077 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sllk_Verlichting' changed from NULL to OFF
2023-01-16 14:41:13.107 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_RelatieveOpbrengst' changed from NULL to 2
2023-01-16 14:41:13.141 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Leeslamp_Schakelaar' changed from NULL to ON
2023-01-16 14:41:13.189 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestWindChillTemperature' changed from NULL to 2.2777777777777777 °C
2023-01-16 14:41:13.231 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Verlichting' changed from NULL to OFF
2023-01-16 14:41:13.252 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'LuxaflexTuin_VenetianBlind' changed from NULL to 67.0
2023-01-16 14:41:13.267 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveNode012ZWA003NanoMoteGwen_SceneNumber' changed from NULL to 4.0
2023-01-16 14:41:13.281 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'BuitenBibliotheek' changed from NULL to OFF
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:13.300 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state raw type (image/jpeg): 8611 bytes (StringType) on item Slk_SonosPlay3_CurrentAlbumCoverArt of type ImageItem, ignoring it
==> /var/log/openhab/events.log <==
2023-01-16 14:41:13.315 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaamKleedkamer_Positie' changed from NULL to 0.0
2023-01-16 14:41:13.339 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_Mute' changed from NULL to OFF
2023-01-16 14:41:13.433 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_Lamp_Links_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:13.447 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_RodeLamp_Dimmer' changed from NULL to 63.0
2023-01-16 14:41:13.465 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_CurrentAlbum' changed from NULL to 
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:13.512 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state PAUSE (StringType) on item Slk_SonosPlay3_MediaControl of type PlayerItem, ignoring it
==> /var/log/openhab/events.log <==
2023-01-16 14:41:13.551 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_Bedrijfsuren' changed from NULL to -3.83874367626667E8 s
2023-01-16 14:41:13.606 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Temperatuur_Luchtvochtigheid' changed from NULL to 53.0
2023-01-16 14:41:13.619 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bw_Windmolen_DeBonteHen_EnergieVerwachting' changed from NULL to 2789500 kWh
2023-01-16 14:41:13.647 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveBibliotheekUplighter_Dimmer' changed from NULL to 23.0
2023-01-16 14:41:13.693 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_SonosSub_Mute' changed from NULL to OFF
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:13.739 [WARN ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: raised an error during connection setup: Read timed out.
2023-01-16 14:41:13.743 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:41:13.768 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestTemperatuur' changed from NULL to 3.388888888888889 °C
2023-01-16 14:41:13.791 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZWaveNode003TZ67WallPlugDimmer_Dimmer' changed from NULL to 100.0
2023-01-16 14:41:13.818 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_Temperatuur_Temperatuur' changed from NULL to 19.5
2023-01-16 14:41:13.831 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaamBadkamer_Positie' changed from NULL to 0.0
2023-01-16 14:41:13.857 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_MediaArtist' changed from NULL to 
2023-01-16 14:41:13.879 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_Volume' changed from NULL to 10.0
2023-01-16 14:41:13.891 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Lamp_1' changed from NULL to ON
2023-01-16 14:41:13.906 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_RodeBank_Lamp_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:13.927 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wnk_Trapspotjes_Schakelaar' changed from NULL to OFF
2023-01-16 14:41:13.940 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'LIGHTING22011632212_DimmingLevel' changed from NULL to 46.0
2023-01-16 14:41:13.955 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bibl_RodeBank_Lamp_Dimniveau' changed from NULL to 55.0
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:14.161 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state raw type (image/png): 27882 bytes (StringType) on item Wrk_SonosConnectAmp_CurrentAlbumCoverArt of type ImageItem, ignoring it
2023-01-16 14:41:14.495 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state PLAY (StringType) on item Wrk_SonosConnectAmp_MediaControl of type PlayerItem, ignoring it
==> /var/log/openhab/events.log <==
2023-01-16 14:41:14.515 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestTemperatuur' changed from 3.388888888888889 °C to 3.5 °C
2023-01-16 14:41:14.537 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestWindChillTemperature' changed from 2.2777777777777777 °C to 2.111111111111111 °C
2023-01-16 14:41:14.541 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestPrecipitationRate' changed from 0 ? to UNDEF
2023-01-16 14:41:14.543 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'TempestPrecipitationTotal' changed from 0 ? to UNDEF
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:14.756 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state raw type (image/jpeg): 8611 bytes (StringType) on item Slk_SonosPlay3_CurrentAlbumCoverArt of type ImageItem, ignoring it
2023-01-16 14:41:14.846 [ERROR] [org.openhab.core.items.GenericItem  ] - Tried to set invalid state PAUSE (StringType) on item Slk_SonosPlay3_MediaControl of type PlayerItem, ignoring it
2023-01-16 14:41:15.184 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:8391c27eeb with scan time of 60
2023-01-16 14:41:15.185 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:8391c27eeb
2023-01-16 14:41:15.187 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.213 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:8391c27eeb' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.219 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
2023-01-16 14:41:15.221 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:8391c27eeb.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.242 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:serial_zstick:8391c27eeb' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.303 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node12' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node12.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.306 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node13' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.309 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node14' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.315 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node10' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.317 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node10.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.321 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node12' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.321 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node14.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.327 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node15' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.331 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node14' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.333 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node10' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.339 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node13.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.342 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node3' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.344 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node13' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.345 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node3.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.349 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node3' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.352 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node15.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.354 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node15' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.376 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node2' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.380 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node6' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.386 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node9' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.388 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node6.
2023-01-16 14:41:15.390 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node2.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.393 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node6' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.395 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node2' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.400 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node8' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.403 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node9.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.406 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node5' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.410 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node9' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.413 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node5.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.416 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node7' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-01-16 14:41:15.419 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:15.422 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node8.
2023-01-16 14:41:15.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:8391c27eeb:node7.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:15.427 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node8' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-01-16 14:41:15.429 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zwave:device:8391c27eeb:node7' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:16.423 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2023-01-16 14:41:19.746 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:41:20.242 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port '/dev/ttyAMA0'
2023-01-16 14:41:20.252 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Starting receive thread
2023-01-16 14:41:20.257 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2023-01-16 14:41:20.257 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Starting ZWave thread: Receive
2023-01-16 14:41:20.258 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initialising ZWave controller
2023-01-16 14:41:20.287 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2023-01-16 14:41:20.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2023-01-16 14:41:20.288 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2023-01-16 14:41:20.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:20.290 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2023-01-16 14:41:20.292 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2023-01-16 14:41:20.294 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Scheduling network mesh heal for 12 hours time.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:21.029 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3861 to 0.3862
2023-01-16 14:41:21.036 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248347 to 0.0248348
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:23.291 [DEBUG] [.ZWaveController$InitializeDelayTask] - Initialising network
2023-01-16 14:41:23.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 0 to queue - size 1
2023-01-16 14:41:23.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:23.318 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 15 E9 
2023-01-16 14:41:23.320 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2023-01-16 14:41:23.348 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-01-16 14:41:23.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 0: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-01-16 14:41:23.353 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 1 to queue - size 1
2023-01-16 14:41:23.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-01-16 14:41:23.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 2 to queue - size 2
2023-01-16 14:41:23.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-01-16 14:41:23.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 3 to queue - size 3
2023-01-16 14:41:23.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-01-16 14:41:23.364 [DEBUG] [rialmessage.GetSucNodeIdMessageClass] - Get SUC NodeID
2023-01-16 14:41:23.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 4 to queue - size 4
2023-01-16 14:41:23.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2023-01-16 14:41:25.351 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 0: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2023-01-16 14:41:25.353 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 0: Transaction is current transaction, so clearing!!!!!
2023-01-16 14:41:25.355 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 0: Transaction CANCELLED
2023-01-16 14:41:25.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:0 CANCELLED
2023-01-16 14:41:25.364 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:25.368 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 20 DC 
2023-01-16 14:41:25.369 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2023-01-16 14:41:25.398 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-01-16 14:41:25.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-01-16 14:41:27.401 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 1: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2023-01-16 14:41:27.403 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 1: Transaction is current transaction, so clearing!!!!!
2023-01-16 14:41:27.404 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1: Transaction CANCELLED
2023-01-16 14:41:27.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:1 CANCELLED
2023-01-16 14:41:27.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:27.413 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 07 FB 
2023-01-16 14:41:27.414 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2023-01-16 14:41:27.438 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-01-16 14:41:27.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-01-16 14:41:29.442 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 2: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2023-01-16 14:41:29.443 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 2: Transaction is current transaction, so clearing!!!!!
2023-01-16 14:41:29.445 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2: Transaction CANCELLED
2023-01-16 14:41:29.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2 CANCELLED
2023-01-16 14:41:29.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:29.464 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 06 96 0F 65 
2023-01-16 14:41:29.466 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2023-01-16 14:41:29.488 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-01-16 14:41:29.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-01-16 14:41:31.491 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 3: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2023-01-16 14:41:31.493 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 3: Transaction is current transaction, so clearing!!!!!
2023-01-16 14:41:31.495 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 3: Transaction CANCELLED
2023-01-16 14:41:31.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:3 CANCELLED
2023-01-16 14:41:31.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2023-01-16 14:41:31.503 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 56 AA 
2023-01-16 14:41:31.506 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2023-01-16 14:41:31.528 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2023-01-16 14:41:31.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2023-01-16 14:41:31.750 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:41:32.410 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3862 to 0.3864
2023-01-16 14:41:32.418 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248348 to 0.024835
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:33.530 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 4: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2023-01-16 14:41:33.532 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 4: Transaction is current transaction, so clearing!!!!!
2023-01-16 14:41:33.533 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 4: Transaction CANCELLED
2023-01-16 14:41:33.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:4 CANCELLED
2023-01-16 14:41:33.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
==> /var/log/openhab/events.log <==
2023-01-16 14:41:43.859 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3864 to 0.3866
2023-01-16 14:41:43.867 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.024835 to 0.0248352
2023-01-16 14:41:55.211 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3866 to 0.3867
2023-01-16 14:41:55.216 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248352 to 0.0248353
==> /var/log/openhab/openhab.log <==
2023-01-16 14:41:55.754 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:42:05.823 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:41:05.758+0100 to 2023-01-16T14:42:05.803+0100
2023-01-16 14:42:06.556 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3867 to 0.3869
2023-01-16 14:42:06.562 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248353 to 0.0248355
2023-01-16 14:42:18.404 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3869 to 0.387
2023-01-16 14:42:18.411 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248355 to 0.0248356
2023-01-16 14:42:29.737 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.387 to 0.3871
2023-01-16 14:42:29.741 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248356 to 0.0248357
2023-01-16 14:42:41.240 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3871 to 0.3874
2023-01-16 14:42:41.245 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248357 to 0.024836
==> /var/log/openhab/openhab.log <==
2023-01-16 14:42:43.758 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:42:52.558 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3874 to 0.3875
2023-01-16 14:42:52.564 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.024836 to 0.0248361
2023-01-16 14:43:04.034 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3875 to 0.3876
2023-01-16 14:43:04.042 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248361 to 0.0248362
2023-01-16 14:43:05.825 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:42:05.803+0100 to 2023-01-16T14:43:05.805+0100
2023-01-16 14:43:15.518 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3876 to 0.3877
2023-01-16 14:43:15.521 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248362 to 0.0248363
2023-01-16 14:43:26.867 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3877 to 0.3878
2023-01-16 14:43:26.873 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248363 to 0.0248364
2023-01-16 14:43:38.108 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3878 to 0.388
2023-01-16 14:43:38.112 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248364 to 0.0248366
2023-01-16 14:43:49.533 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.388 to 0.3881
2023-01-16 14:43:49.538 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248366 to 0.0248367
2023-01-16 14:44:00.928 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3881 to 0.3882
2023-01-16 14:44:00.933 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248367 to 0.0248368
2023-01-16 14:44:05.827 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:43:05.805+0100 to 2023-01-16T14:44:05.806+0100
2023-01-16 14:44:12.478 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3882 to 0.3883
2023-01-16 14:44:12.483 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248368 to 0.0248369
==> /var/log/openhab/openhab.log <==
2023-01-16 14:44:15.290 [INFO ] [s.internal.handler.ZonePlayerHandler] - UPnP device RINCON_000E5873259801400 is present (thing sonos:PLAY3:RINCON_000E5873259801400)
==> /var/log/openhab/events.log <==
2023-01-16 14:44:15.294 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sonos:PLAY3:RINCON_000E5873259801400' changed from OFFLINE (COMMUNICATION_ERROR): The UPnP device RINCON_000E5873259801400 is not yet registered. to ONLINE
2023-01-16 14:44:15.394 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Slk_SonosPlay3_MediaControl' changed from NULL to PAUSE
==> /var/log/openhab/openhab.log <==
2023-01-16 14:44:19.760 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: socket I/O failed 5 times.
2023-01-16 14:44:19.762 [WARN ] [.internal.handler.VeluxBridgeHandler] - velux bridge login sequence failed; expecting bridge is OFFLINE.
==> /var/log/openhab/events.log <==
2023-01-16 14:44:19.768 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'velux:klf200:192_168_0_220' changed from UNKNOWN to OFFLINE (CONFIGURATION_ERROR): Inloggen is mislukt.
2023-01-16 14:44:23.863 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3883 to 0.3884
2023-01-16 14:44:23.870 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248369 to 0.024837
==> /var/log/openhab/openhab.log <==
2023-01-16 14:44:29.783 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:44:32.788 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:44:35.082 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3884 to 0.3885
2023-01-16 14:44:35.095 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.024837 to 0.0248371
==> /var/log/openhab/openhab.log <==
2023-01-16 14:44:38.794 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:44:46.311 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3885 to 0.3886
2023-01-16 14:44:46.317 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248371 to 0.0248372
==> /var/log/openhab/openhab.log <==
2023-01-16 14:44:50.799 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:44:57.283 [INFO ] [s.internal.handler.ZonePlayerHandler] - UPnP device RINCON_000E58D0B87201400 is present (thing sonos:CONNECTAMP:RINCON_000E58D0B87201400)
==> /var/log/openhab/events.log <==
2023-01-16 14:44:57.286 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sonos:CONNECTAMP:RINCON_000E58D0B87201400' changed from OFFLINE (COMMUNICATION_ERROR): The UPnP device RINCON_000E58D0B87201400 is not yet registered. to ONLINE
2023-01-16 14:44:57.469 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_MediaControl' changed from NULL to PLAY
2023-01-16 14:44:57.709 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3886 to 0.3888
2023-01-16 14:44:57.715 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248372 to 0.0248374
2023-01-16 14:44:57.888 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Wrk_SonosConnectAmp_CurrentAlbumCoverArt' changed from NULL to raw type (image/png): 27882 bytes
2023-01-16 14:45:05.828 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:44:05.806+0100 to 2023-01-16T14:45:05.808+0100
2023-01-16 14:45:08.995 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3888 to 0.3889
2023-01-16 14:45:09.001 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248374 to 0.0248375
==> /var/log/openhab/openhab.log <==
2023-01-16 14:45:14.803 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:45:20.454 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3889 to 0.389
2023-01-16 14:45:20.461 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248375 to 0.0248376
2023-01-16 14:45:31.780 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.389 to 0.3891
2023-01-16 14:45:31.791 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248376 to 0.0248377
2023-01-16 14:45:43.675 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3891 to 0.3893
2023-01-16 14:45:43.680 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248377 to 0.0248379
==> /var/log/openhab/openhab.log <==
2023-01-16 14:46:02.806 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:46:05.829 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:45:05.808+0100 to 2023-01-16T14:46:05.809+0100
2023-01-16 14:46:06.528 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3893 to 0.3895
2023-01-16 14:46:06.533 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248379 to 0.0248381
2023-01-16 14:46:17.876 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3895 to 0.3897
2023-01-16 14:46:17.881 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248381 to 0.0248383
2023-01-16 14:46:29.431 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3897 to 0.3898
2023-01-16 14:46:29.438 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248383 to 0.0248384
2023-01-16 14:46:40.918 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3898 to 0.39
2023-01-16 14:46:40.923 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248384 to 0.0248386
2023-01-16 14:46:52.504 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.39 to 0.3901
2023-01-16 14:46:52.510 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248386 to 0.0248387
2023-01-16 14:47:03.910 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3901 to 0.3903
2023-01-16 14:47:03.918 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248387 to 0.0248389
2023-01-16 14:47:05.831 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:46:05.809+0100 to 2023-01-16T14:47:05.811+0100
2023-01-16 14:47:15.125 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3903 to 0.3905
2023-01-16 14:47:15.131 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248389 to 0.0248391
2023-01-16 14:47:26.444 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3905 to 0.3906
2023-01-16 14:47:26.448 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248391 to 0.0248392
2023-01-16 14:47:37.842 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3906 to 0.3907
2023-01-16 14:47:37.848 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248392 to 0.0248393
==> /var/log/openhab/openhab.log <==
2023-01-16 14:47:38.809 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: socket I/O failed 5 times.
2023-01-16 14:47:38.817 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:47:41.821 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:47:47.824 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:47:49.078 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3907 to 0.3908
2023-01-16 14:47:49.085 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248393 to 0.0248394
==> /var/log/openhab/openhab.log <==
2023-01-16 14:47:59.830 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:48:00.461 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3908 to 0.391
2023-01-16 14:48:00.465 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248394 to 0.0248396
2023-01-16 14:48:05.832 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:47:05.811+0100 to 2023-01-16T14:48:05.812+0100
2023-01-16 14:48:11.861 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.391 to 0.3911
2023-01-16 14:48:11.867 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248396 to 0.0248397
2023-01-16 14:48:23.514 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3911 to 0.3913
2023-01-16 14:48:23.519 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248397 to 0.0248399
==> /var/log/openhab/openhab.log <==
2023-01-16 14:48:23.833 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:48:34.839 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3913 to 0.3914
2023-01-16 14:48:34.847 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248399 to 0.02484
2023-01-16 14:48:46.317 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3914 to 0.3915
2023-01-16 14:48:46.323 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.02484 to 0.0248401
2023-01-16 14:48:57.612 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3915 to 0.3916
2023-01-16 14:48:57.619 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248401 to 0.0248402
2023-01-16 14:49:05.834 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:48:05.812+0100 to 2023-01-16T14:49:05.814+0100
2023-01-16 14:49:09.015 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3916 to 0.3918
2023-01-16 14:49:09.022 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248402 to 0.0248404
==> /var/log/openhab/openhab.log <==
2023-01-16 14:49:11.837 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:49:20.547 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3918 to 0.3919
2023-01-16 14:49:20.553 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248404 to 0.0248405
2023-01-16 14:49:31.903 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3919 to 0.392
2023-01-16 14:49:31.907 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248405 to 0.0248406
2023-01-16 14:49:43.112 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.392 to 0.3921
2023-01-16 14:49:43.118 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248406 to 0.0248407
2023-01-16 14:49:54.329 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3921 to 0.3922
2023-01-16 14:49:54.337 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248407 to 0.0248408
2023-01-16 14:50:05.835 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:49:05.814+0100 to 2023-01-16T14:50:05.815+0100
2023-01-16 14:50:06.125 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3922 to 0.3923
2023-01-16 14:50:06.130 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248408 to 0.0248409
2023-01-16 14:50:17.496 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3923 to 0.3925
2023-01-16 14:50:17.500 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248409 to 0.0248411
2023-01-16 14:50:28.840 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3925 to 0.3926
2023-01-16 14:50:28.846 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248411 to 0.0248412
2023-01-16 14:50:40.443 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3926 to 0.3927
2023-01-16 14:50:40.450 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248412 to 0.0248413
==> /var/log/openhab/openhab.log <==
2023-01-16 14:50:47.840 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: socket I/O failed 5 times.
2023-01-16 14:50:47.841 [WARN ] [.internal.handler.VeluxBridgeHandler] - velux bridge login sequence failed; expecting bridge is OFFLINE.
2023-01-16 14:50:47.852 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:50:50.856 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:50:51.762 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3927 to 0.3928
2023-01-16 14:50:51.767 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248413 to 0.0248414
==> /var/log/openhab/openhab.log <==
2023-01-16 14:50:56.859 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:51:02.962 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3928 to 0.3929
2023-01-16 14:51:02.969 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248414 to 0.0248415
2023-01-16 14:51:05.836 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:50:05.815+0100 to 2023-01-16T14:51:05.816+0100
==> /var/log/openhab/openhab.log <==
2023-01-16 14:51:08.864 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:51:25.768 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3929 to 0.393
2023-01-16 14:51:25.777 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248415 to 0.0248416
==> /var/log/openhab/openhab.log <==
2023-01-16 14:51:32.868 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:51:37.405 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.393 to 0.3932
2023-01-16 14:51:37.414 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248416 to 0.0248418
2023-01-16 14:51:48.834 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3932 to 0.3933
2023-01-16 14:51:48.841 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248418 to 0.0248419
2023-01-16 14:52:05.838 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:51:05.816+0100 to 2023-01-16T14:52:05.817+0100
2023-01-16 14:52:11.827 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3933 to 0.3934
2023-01-16 14:52:11.832 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248419 to 0.024842
==> /var/log/openhab/openhab.log <==
2023-01-16 14:52:20.875 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:52:23.061 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3934 to 0.3935
2023-01-16 14:52:23.068 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.024842 to 0.0248421
2023-01-16 14:52:34.107 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3935 to 0.3936
2023-01-16 14:52:34.113 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248421 to 0.0248422
2023-01-16 14:52:45.483 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3936 to 0.3937
2023-01-16 14:52:45.489 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248422 to 0.0248423
2023-01-16 14:53:05.839 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:52:05.817+0100 to 2023-01-16T14:53:05.819+0100
2023-01-16 14:53:08.257 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3937 to 0.3939
2023-01-16 14:53:08.264 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248423 to 0.0248425
2023-01-16 14:53:19.621 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3939 to 0.394
2023-01-16 14:53:19.626 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248425 to 0.0248426
2023-01-16 14:53:31.291 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.394 to 0.3941
2023-01-16 14:53:31.297 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248426 to 0.0248427
2023-01-16 14:53:42.633 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3941 to 0.3942
2023-01-16 14:53:42.637 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248427 to 0.0248428
2023-01-16 14:53:54.093 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3942 to 0.3943
2023-01-16 14:53:54.097 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248428 to 0.0248429
==> /var/log/openhab/openhab.log <==
2023-01-16 14:53:56.877 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: socket I/O failed 5 times.
2023-01-16 14:53:56.883 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): actuator with uniqueIndex=53:25:2E:26:16:24:14:F4 is not registered
2023-01-16 14:53:56.885 [WARN ] [rnal.handler.ChannelActuatorPosition] - handleRefresh(): unknown actuator.
2023-01-16 14:53:56.887 [WARN ] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:window:192_168_0_220:Raam_Waskamer:position,REFRESH): updating of item velux:window:192_168_0_220:Raam_Waskamer:position (type velux:window/position) failed.
2023-01-16 14:53:56.894 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:53:59.897 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:54:05.369 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3943 to 0.3944
2023-01-16 14:54:05.374 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248429 to 0.024843
2023-01-16 14:54:05.841 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:53:05.819+0100 to 2023-01-16T14:54:05.820+0100
==> /var/log/openhab/openhab.log <==
2023-01-16 14:54:05.903 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
2023-01-16 14:54:17.906 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:54:28.316 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3944 to 0.3945
2023-01-16 14:54:28.352 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.024843 to 0.0248431
2023-01-16 14:54:39.694 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3945 to 0.3947
2023-01-16 14:54:39.698 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248431 to 0.0248433
==> /var/log/openhab/openhab.log <==
2023-01-16 14:54:41.911 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).
==> /var/log/openhab/events.log <==
2023-01-16 14:54:50.949 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3947 to 0.3948
2023-01-16 14:54:50.954 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248433 to 0.0248434
2023-01-16 14:55:05.842 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DatumEnTijd' changed from 2023-01-16T14:54:05.820+0100 to 2023-01-16T14:55:05.821+0100
2023-01-16 14:55:13.809 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3948 to 0.3949
2023-01-16 14:55:13.814 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248434 to 0.0248435
2023-01-16 14:55:25.423 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonDagopbrengst' changed from 0.3949 to 0.395
2023-01-16 14:55:25.429 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ZonJaaropbrengst' changed from 0.0248435 to 0.0248436
==> /var/log/openhab/openhab.log <==
2023-01-16 14:55:29.915 [INFO ] [x.internal.bridge.slip.io.Connection] - io() on 192.168.0.220: Exception occurred during I/O: raised a non-recoverable error during connection setup: Connection refused (Connection refused).