2023-01-24 19:59:39.902 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Querying state active
2023-01-24 19:59:39.903 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Deactivating component
2023-01-24 19:59:39.903 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : registration change queue [unregistered]
2023-01-24 19:59:39.906 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Querying state active
2023-01-24 19:59:39.907 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : getting deactivate: deactivate
2023-01-24 19:59:39.908 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Locating method deactivate in class org.openhab.io.homekit.internal.HomekitCommandExtension
2023-01-24 19:59:39.908 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:39.909 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Locating method deactivate in class org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension
2023-01-24 19:59:39.910 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Declared Method org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:39.910 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Locating method deactivate in class java.lang.Object
2023-01-24 19:59:39.911 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Declared Method java.lang.Object.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:39.911 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : deactivate method [deactivate] not found, ignoring
2023-01-24 19:59:39.912 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : DependencyManager: Homekit close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@21d2c5d6 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.io.homekit.Homekit}={port=9123, qrCode=X-HM://001408XXEMWLO, thermostatTargetModeHeat=Comfort, service.id=659, service.bundleid=283, service.scope=bundle, thermostatCurrentModeOff=Economy, thermostatCurrentModeHeating=Comfort, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, thermostatTargetModeOff=Economy, component.id=376, service.config.factory=false, setupId=MWLO, useOHmDNS=true, networkInterface=192.168.1.10, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]}] service: [org.openhab.io.homekit.internal.HomekitImpl@c82543f]]]
2023-01-24 19:59:39.913 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-01-24 19:59:39.913 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Changed state from active to satisfied
2023-01-24 19:59:39.915 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Deactivating dependency managers
2023-01-24 19:59:39.917 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Querying state active
2023-01-24 19:59:39.918 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : getting deactivate: deactivate
2023-01-24 19:59:39.918 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Locating method deactivate in class org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:39.919 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Declared Method org.openhab.io.homekit.internal.HomekitImpl.deactivate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:39.920 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Found deactivate method: protected void org.openhab.io.homekit.internal.HomekitImpl.deactivate()
2023-01-24 19:59:39.920 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : invoking deactivate: deactivate: parameters []
2023-01-24 19:59:41.935 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : invoked deactivate: deactivate
2023-01-24 19:59:41.936 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $006 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 7 refpairs: [[RefPair: ref: [{org.openhab.core.service.ReadyService}={service.id=187, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.service.ReadyServiceImpl, component.id=44}] service: [org.openhab.core.internal.service.ReadyServiceImpl@60b334a6]]]
2023-01-24 19:59:41.937 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $005 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 6 refpairs: [[RefPair: ref: [{org.openhab.core.io.transport.mdns.MDNSClient}={service.id=306, service.bundleid=186, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=193}] service: [org.openhab.core.io.transport.mdns.internal.MDNSClientImpl@4b1399fe]]]
2023-01-24 19:59:41.937 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $004 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 5 refpairs: [[RefPair: ref: [{org.osgi.service.cm.ConfigurationAdmin}={osgi.command.scope=cm, service.description=Configuration Admin Service Specification 1.6 Implementation, service.id=40, service.bundleid=12, service.scope=bundle, osgi.command.function=[getConfiguration,getFactoryConfiguration,listConfigurations,createFactoryConfiguration], service.vendor=The Apache Software Foundation, config.plugins=, service.pid=org.apache.felix.cm.ConfigurationAdmin}] service: [org.apache.felix.cm.impl.ConfigurationAdminImpl@6e2825a7]]]
2023-01-24 19:59:41.938 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $003 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 4 refpairs: [[RefPair: ref: [{org.openhab.core.items.MetadataRegistry}={service.id=188, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=36}] service: [org.openhab.core.internal.items.MetadataRegistryImpl@c649af3]]]
2023-01-24 19:59:41.939 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $002 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 3 refpairs: [[RefPair: ref: [{org.openhab.core.net.NetworkAddressService}={service.id=198, service.bundleid=149, service.scope=bundle, service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=49}] service: [org.openhab.core.net.NetUtil@367c5903]]]
2023-01-24 19:59:41.940 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 2 refpairs: [[RefPair: ref: [{org.openhab.core.items.ItemRegistry}={service.id=189, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=31}] service: [org.openhab.core.internal.items.ItemRegistryImpl@4f135d6e]]]
2023-01-24 19:59:41.940 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@2b2aaea6 at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.storage.StorageService}={service.id=342, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=237, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage}] service: [org.openhab.core.storage.json.internal.JsonStorageService@dd6a5fd]]]
2023-01-24 19:59:41.941 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified
2023-01-24 19:59:41.942 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Changed state from active to satisfied
2023-01-24 19:59:41.942 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Disabling dependency managers
2023-01-24 19:59:41.945 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : unregistering service listener for dependency Homekit
2023-01-24 19:59:41.946 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Querying state satisfied
2023-01-24 19:59:41.946 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Changed state from satisfied to disposed
2023-01-24 19:59:41.947 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(375)] : Disposing component (reason: 6)
2023-01-24 19:59:41.947 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Querying state satisfied
2023-01-24 19:59:41.948 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Deactivating component
2023-01-24 19:59:41.949 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : registration change queue [unregistered]
2023-01-24 19:59:41.950 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Deactivating dependency managers
2023-01-24 19:59:41.956 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Disabling dependency managers
2023-01-24 19:59:41.958 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $000
2023-01-24 19:59:41.960 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $001
2023-01-24 19:59:41.962 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $002
2023-01-24 19:59:41.965 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $003
2023-01-24 19:59:41.967 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $004
2023-01-24 19:59:41.969 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $005
2023-01-24 19:59:41.971 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : unregistering service listener for dependency $006
2023-01-24 19:59:41.971 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Querying state satisfied
2023-01-24 19:59:41.972 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Changed state from satisfied to disposed
2023-01-24 19:59:41.973 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(376)] : Disposing component (reason: 6)
2023-01-24 19:59:41.988 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : BundleComponentActivator : ComponentHolder created.
2023-01-24 19:59:41.989 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : BundleComponentActivator : ComponentHolder created.
2023-01-24 19:59:41.992 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : ConfigurableComponentHolder configuration updated for pid org.openhab.homekit with change count 4
2023-01-24 19:59:41.993 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Dependency Manager created Homekitinterface=org.openhab.io.homekit.Homekit, filter=null, policy=static, cardinality=1..1, bind=setHomekit, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-01-24 19:59:41.994 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component created: DS=DS13, implementation=org.openhab.io.homekit.internal.HomekitCommandExtension, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.io.homekit.internal.HomekitCommandExtension]
2023-01-24 19:59:41.995 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component Services: scope=singleton, services=[org.openhab.core.io.console.extensions.ConsoleCommandExtension]
2023-01-24 19:59:41.995 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component Properties: {}
2023-01-24 19:59:41.996 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2023-01-24 19:59:41.997 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2023-01-24 19:59:41.997 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component can not be activated since it is in state disabled
2023-01-24 19:59:41.998 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2023-01-24 19:59:41.999 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Updating target filters
2023-01-24 19:59:41.999 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : No change in target property for dependency Homekit: currently registered: false
2023-01-24 19:59:42.000 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] :  No existing service listener to unregister for dependency Homekit
2023-01-24 19:59:42.000 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Setting target property for dependency Homekit to null
2023-01-24 19:59:42.001 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : New service tracker for Homekit, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.io.homekit.Homekit), initialReferenceFilter (objectClass=org.openhab.io.homekit.Homekit)
2023-01-24 19:59:42.002 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : dm Homekit tracker reset (closed)
2023-01-24 19:59:42.003 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : dm Homekit tracker opened
2023-01-24 19:59:42.004 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : registering service listener for dependency Homekit
2023-01-24 19:59:42.005 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Changed state from disabled to unsatisfiedReference
2023-01-24 19:59:42.005 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Component enabled
2023-01-24 19:59:42.006 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : ActivateInternal
2023-01-24 19:59:42.006 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.007 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.008 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Activating component from state unsatisfiedReference
2023-01-24 19:59:42.008 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.009 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.010 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Dependency not satisfied: Homekit
2023-01-24 19:59:42.010 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Not all dependencies satisfied, cannot activate
2023-01-24 19:59:42.011 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $000interface=org.openhab.core.storage.StorageService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2023-01-24 19:59:42.012 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $001interface=org.openhab.core.items.ItemRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1
2023-01-24 19:59:42.013 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $002interface=org.openhab.core.net.NetworkAddressService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=2
2023-01-24 19:59:42.014 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $003interface=org.openhab.core.items.MetadataRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=3
2023-01-24 19:59:42.014 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $004interface=org.osgi.service.cm.ConfigurationAdmin, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=4
2023-01-24 19:59:42.015 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $005interface=org.openhab.core.io.transport.mdns.MDNSClient, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=5
2023-01-24 19:59:42.016 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Dependency Manager created $006interface=org.openhab.core.service.ReadyService, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=6
2023-01-24 19:59:42.016 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Component created: DS=DS14, implementation=org.openhab.io.homekit.internal.HomekitImpl, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=modified configuration-pid=[org.openhab.homekit]
2023-01-24 19:59:42.017 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Component Services: scope=singleton, services=[org.openhab.io.homekit.Homekit]
2023-01-24 19:59:42.018 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Component Properties: {service.config.label=HomeKit Integration, port=9123, service.config.category=io, service.config.description.uri=io:homekit, service.config.factory=false, service.pid=org.openhab.homekit}
2023-01-24 19:59:42.018 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2023-01-24 19:59:42.019 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2023-01-24 19:59:42.020 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Component can not be activated since it is in state disabled
2023-01-24 19:59:42.020 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2023-01-24 19:59:42.021 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Updating target filters
2023-01-24 19:59:42.022 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $000: currently registered: false
2023-01-24 19:59:42.022 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $000
2023-01-24 19:59:42.023 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $000 to null
2023-01-24 19:59:42.023 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.storage.StorageService), initialReferenceFilter (objectClass=org.openhab.core.storage.StorageService)
2023-01-24 19:59:42.024 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $000 tracker reset (closed)
2023-01-24 19:59:42.025 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.storage.StorageService}={service.id=342, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=237, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage} (enter)
2023-01-24 19:59:42.026 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.027 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.storage.StorageService}={service.id=342, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=237, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage} (exit)
2023-01-24 19:59:42.028 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $000 tracker opened
2023-01-24 19:59:42.028 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $000
2023-01-24 19:59:42.029 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $001: currently registered: false
2023-01-24 19:59:42.030 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $001
2023-01-24 19:59:42.031 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $001 to null
2023-01-24 19:59:42.031 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.items.ItemRegistry), initialReferenceFilter (objectClass=org.openhab.core.items.ItemRegistry)
2023-01-24 19:59:42.032 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $001 tracker reset (closed)
2023-01-24 19:59:42.033 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=189, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=31} (enter)
2023-01-24 19:59:42.034 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.035 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=189, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=31} (exit)
2023-01-24 19:59:42.036 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $001 tracker opened
2023-01-24 19:59:42.036 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $001
2023-01-24 19:59:42.037 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $002: currently registered: false
2023-01-24 19:59:42.037 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $002
2023-01-24 19:59:42.038 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $002 to null
2023-01-24 19:59:42.039 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.net.NetworkAddressService), initialReferenceFilter (objectClass=org.openhab.core.net.NetworkAddressService)
2023-01-24 19:59:42.039 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $002 tracker reset (closed)
2023-01-24 19:59:42.041 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=198, service.bundleid=149, service.scope=bundle, service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=49} (enter)
2023-01-24 19:59:42.041 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.042 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=198, service.bundleid=149, service.scope=bundle, service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=49} (exit)
2023-01-24 19:59:42.043 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $002 tracker opened
2023-01-24 19:59:42.043 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $002
2023-01-24 19:59:42.044 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $003: currently registered: false
2023-01-24 19:59:42.045 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $003
2023-01-24 19:59:42.045 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $003 to null
2023-01-24 19:59:42.046 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $003, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.items.MetadataRegistry), initialReferenceFilter (objectClass=org.openhab.core.items.MetadataRegistry)
2023-01-24 19:59:42.047 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $003 tracker reset (closed)
2023-01-24 19:59:42.048 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $003 tracking 4 SingleStatic added {org.openhab.core.items.MetadataRegistry}={service.id=188, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=36} (enter)
2023-01-24 19:59:42.049 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $003 tracking 4 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.050 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $003 tracking 4 SingleStatic added {org.openhab.core.items.MetadataRegistry}={service.id=188, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=36} (exit)
2023-01-24 19:59:42.050 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $003 tracker opened
2023-01-24 19:59:42.051 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $003
2023-01-24 19:59:42.051 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $004: currently registered: false
2023-01-24 19:59:42.052 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $004
2023-01-24 19:59:42.053 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $004 to null
2023-01-24 19:59:42.053 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $004, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.cm.ConfigurationAdmin), initialReferenceFilter (objectClass=org.osgi.service.cm.ConfigurationAdmin)
2023-01-24 19:59:42.054 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $004 tracker reset (closed)
2023-01-24 19:59:42.055 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $004 tracking 5 SingleStatic added {org.osgi.service.cm.ConfigurationAdmin}={osgi.command.scope=cm, service.description=Configuration Admin Service Specification 1.6 Implementation, service.id=40, service.bundleid=12, service.scope=bundle, osgi.command.function=[getConfiguration,getFactoryConfiguration,listConfigurations,createFactoryConfiguration], service.vendor=The Apache Software Foundation, config.plugins=, service.pid=org.apache.felix.cm.ConfigurationAdmin} (enter)
2023-01-24 19:59:42.056 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $004 tracking 5 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.057 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $004 tracking 5 SingleStatic added {org.osgi.service.cm.ConfigurationAdmin}={osgi.command.scope=cm, service.description=Configuration Admin Service Specification 1.6 Implementation, service.id=40, service.bundleid=12, service.scope=bundle, osgi.command.function=[getConfiguration,getFactoryConfiguration,listConfigurations,createFactoryConfiguration], service.vendor=The Apache Software Foundation, config.plugins=, service.pid=org.apache.felix.cm.ConfigurationAdmin} (exit)
2023-01-24 19:59:42.058 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $004 tracker opened
2023-01-24 19:59:42.058 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $004
2023-01-24 19:59:42.059 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $005: currently registered: false
2023-01-24 19:59:42.059 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $005
2023-01-24 19:59:42.060 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $005 to null
2023-01-24 19:59:42.061 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $005, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.mdns.MDNSClient), initialReferenceFilter (objectClass=org.openhab.core.io.transport.mdns.MDNSClient)
2023-01-24 19:59:42.061 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $005 tracker reset (closed)
2023-01-24 19:59:42.063 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $005 tracking 6 SingleStatic added {org.openhab.core.io.transport.mdns.MDNSClient}={service.id=306, service.bundleid=186, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=193} (enter)
2023-01-24 19:59:42.063 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $005 tracking 6 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.064 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $005 tracking 6 SingleStatic added {org.openhab.core.io.transport.mdns.MDNSClient}={service.id=306, service.bundleid=186, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=193} (exit)
2023-01-24 19:59:42.065 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $005 tracker opened
2023-01-24 19:59:42.065 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $005
2023-01-24 19:59:42.066 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : No change in target property for dependency $006: currently registered: false
2023-01-24 19:59:42.067 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] :  No existing service listener to unregister for dependency $006
2023-01-24 19:59:42.067 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Setting target property for dependency $006 to null
2023-01-24 19:59:42.068 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : New service tracker for $006, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.service.ReadyService), initialReferenceFilter (objectClass=org.openhab.core.service.ReadyService)
2023-01-24 19:59:42.069 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $006 tracker reset (closed)
2023-01-24 19:59:42.070 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $006 tracking 7 SingleStatic added {org.openhab.core.service.ReadyService}={service.id=187, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.service.ReadyServiceImpl, component.id=44} (enter)
2023-01-24 19:59:42.071 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $006 tracking 7 SingleStatic active: false trackerOpened: false optional: false
2023-01-24 19:59:42.072 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $006 tracking 7 SingleStatic added {org.openhab.core.service.ReadyService}={service.id=187, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.service.ReadyServiceImpl, component.id=44} (exit)
2023-01-24 19:59:42.072 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : dm $006 tracker opened
2023-01-24 19:59:42.073 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registering service listener for dependency $006
2023-01-24 19:59:42.073 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Changed state from disabled to unsatisfiedReference
2023-01-24 19:59:42.074 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Component enabled
2023-01-24 19:59:42.074 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : ActivateInternal
2023-01-24 19:59:42.075 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.075 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.076 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Activating component from state unsatisfiedReference
2023-01-24 19:59:42.076 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.077 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.077 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Changed state from unsatisfiedReference to satisfied
2023-01-24 19:59:42.078 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : registration change queue [registered]
2023-01-24 19:59:42.079 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : dm Homekit tracking 1 SingleStatic added {org.openhab.io.homekit.Homekit}={port=9123, qrCode=X-HM://001408XXEMWLO, thermostatTargetModeHeat=Comfort, service.id=662, service.bundleid=283, service.scope=bundle, thermostatCurrentModeOff=Economy, thermostatCurrentModeHeating=Comfort, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, thermostatTargetModeOff=Economy, component.id=378, service.config.factory=false, setupId=MWLO, useOHmDNS=true, networkInterface=192.168.1.10, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]} (enter)
2023-01-24 19:59:42.080 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : ActivateInternal
2023-01-24 19:59:42.080 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.081 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.081 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Activating component from state unsatisfiedReference
2023-01-24 19:59:42.082 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.082 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state unsatisfiedReference
2023-01-24 19:59:42.083 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Changed state from unsatisfiedReference to satisfied
2023-01-24 19:59:42.083 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : registration change queue [registered]
2023-01-24 19:59:42.085 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Checking constructor public org.openhab.io.homekit.internal.HomekitCommandExtension()
2023-01-24 19:59:42.085 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Found constructor with 0 arguments : public org.openhab.io.homekit.internal.HomekitCommandExtension()
2023-01-24 19:59:42.086 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : getting bind: setHomekit
2023-01-24 19:59:42.086 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Locating method setHomekit in class org.openhab.io.homekit.internal.HomekitCommandExtension
2023-01-24 19:59:42.087 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : doFindMethod: Looking for method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit
2023-01-24 19:59:42.087 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit([interface org.osgi.framework.ServiceReference]) not found
2023-01-24 19:59:42.088 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-01-24 19:59:42.088 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : getReferenceClass: Looking for interface class org.openhab.io.homekit.Homekit through loader of org.openhab.io.homekit.internal.HomekitCommandExtension
2023-01-24 19:59:42.089 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : getParameterClass: Found class org.openhab.io.homekit.Homekit
2023-01-24 19:59:42.089 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.io.homekit.Homekit
2023-01-24 19:59:42.090 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : doFindMethod: Found Method public void org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit(org.openhab.io.homekit.Homekit)
2023-01-24 19:59:42.090 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Found bind method: public void org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit(org.openhab.io.homekit.Homekit)
2023-01-24 19:59:42.091 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Checking constructor public org.openhab.io.homekit.internal.HomekitImpl(org.openhab.core.storage.StorageService,org.openhab.core.items.ItemRegistry,org.openhab.core.net.NetworkAddressService,org.openhab.core.items.MetadataRegistry,org.osgi.service.cm.ConfigurationAdmin,org.openhab.core.io.transport.mdns.MDNSClient,org.openhab.core.service.ReadyService,java.util.Map) throws java.io.IOException,java.security.InvalidAlgorithmParameterException
2023-01-24 19:59:42.091 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.storage.StorageService through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.092 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.storage.StorageService
2023-01-24 19:59:42.092 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.items.ItemRegistry through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.093 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.items.ItemRegistry
2023-01-24 19:59:42.093 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.net.NetworkAddressService through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.093 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.net.NetworkAddressService
2023-01-24 19:59:42.094 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.items.MetadataRegistry through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.094 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.items.MetadataRegistry
2023-01-24 19:59:42.095 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.osgi.service.cm.ConfigurationAdmin through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.095 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.osgi.service.cm.ConfigurationAdmin
2023-01-24 19:59:42.095 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.io.transport.mdns.MDNSClient through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.096 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.io.transport.mdns.MDNSClient
2023-01-24 19:59:42.096 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getReferenceClass: Looking for interface class org.openhab.core.service.ReadyService through loader of org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.097 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getParameterClass: Found class org.openhab.core.service.ReadyService
2023-01-24 19:59:42.097 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Found constructor with 8 arguments : public org.openhab.io.homekit.internal.HomekitImpl(org.openhab.core.storage.StorageService,org.openhab.core.items.ItemRegistry,org.openhab.core.net.NetworkAddressService,org.openhab.core.items.MetadataRegistry,org.osgi.service.cm.ConfigurationAdmin,org.openhab.core.io.transport.mdns.MDNSClient,org.openhab.core.service.ReadyService,java.util.Map) throws java.io.IOException,java.security.InvalidAlgorithmParameterException
2023-01-24 19:59:42.098 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : This thread collected dependencies
2023-01-24 19:59:42.098 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getService (single component manager) dependencies collected.
2023-01-24 19:59:42.098 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state satisfied
2023-01-24 19:59:42.099 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Querying state satisfied
2023-01-24 19:59:42.099 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.storage.StorageService}={service.id=342, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=237, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage}] service: [null]]]
2023-01-24 19:59:42.100 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.items.ItemRegistry}={service.id=189, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=31}] service: [null]]]
2023-01-24 19:59:42.100 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.net.NetworkAddressService}={service.id=198, service.bundleid=149, service.scope=bundle, service.config.category=system, service.pid=org.openhab.network, service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, component.id=49}] service: [null]]]
2023-01-24 19:59:42.101 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $003, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.items.MetadataRegistry}={service.id=188, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=36}] service: [null]]]
2023-01-24 19:59:42.101 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $004, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.cm.ConfigurationAdmin}={osgi.command.scope=cm, service.description=Configuration Admin Service Specification 1.6 Implementation, service.id=40, service.bundleid=12, service.scope=bundle, osgi.command.function=[getConfiguration,getFactoryConfiguration,listConfigurations,createFactoryConfiguration], service.vendor=The Apache Software Foundation, config.plugins=, service.pid=org.apache.felix.cm.ConfigurationAdmin}] service: [null]]]
2023-01-24 19:59:42.102 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $005, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.mdns.MDNSClient}={service.id=306, service.bundleid=186, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=193}] service: [null]]]
2023-01-24 19:59:42.102 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : For dependency $006, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.service.ReadyService}={service.id=187, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.service.ReadyServiceImpl, component.id=44}] service: [null]]]
2023-01-24 19:59:42.195 [WARN ] [istics.impl.base.FloatCharacteristic] - Detected value out of range 13.5. Returning min value instead. Characteristic io.github.hapjava.characteristics.impl.thermostat.TargetTemperatureCharacteristic@70772349
2023-01-24 19:59:42.283 [INFO ] [mekit.internal.HomekitChangeListener] - Created 79 HomeKit items in instance 1 (no change from prior configuration).
2023-01-24 19:59:42.327 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : getting activate: activate
2023-01-24 19:59:42.329 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Locating method activate in class org.openhab.io.homekit.internal.HomekitImpl
2023-01-24 19:59:42.332 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Declared Method org.openhab.io.homekit.internal.HomekitImpl.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:42.334 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Locating method activate in class java.lang.Object
2023-01-24 19:59:42.336 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:42.336 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : activate method [activate] not found, ignoring
2023-01-24 19:59:42.337 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Set implementation object for component
2023-01-24 19:59:42.337 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitImpl(378)] : Changed state from satisfied to active
2023-01-24 19:59:42.338 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : This thread collected dependencies
2023-01-24 19:59:42.338 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : getService (single component manager) dependencies collected.
2023-01-24 19:59:42.339 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state satisfied
2023-01-24 19:59:42.340 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Querying state satisfied
2023-01-24 19:59:42.340 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : For dependency Homekit, optional: false; to bind: [[RefPair: ref: [{org.openhab.io.homekit.Homekit}={port=9123, qrCode=X-HM://001408XXEMWLO, thermostatTargetModeHeat=Comfort, service.id=662, service.bundleid=283, service.scope=bundle, thermostatCurrentModeOff=Economy, thermostatCurrentModeHeating=Comfort, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, thermostatTargetModeOff=Economy, component.id=378, service.config.factory=false, setupId=MWLO, useOHmDNS=true, networkInterface=192.168.1.10, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]}] service: [org.openhab.io.homekit.internal.HomekitImpl@a492046]]]
2023-01-24 19:59:42.341 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : invoking bind: setHomekit: parameters [org.openhab.io.homekit.internal.HomekitImpl]
2023-01-24 19:59:42.342 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : invoked bind: setHomekit
2023-01-24 19:59:42.342 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : getting activate: activate
2023-01-24 19:59:42.343 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Locating method activate in class org.openhab.io.homekit.internal.HomekitCommandExtension
2023-01-24 19:59:42.343 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:42.344 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Locating method activate in class org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension
2023-01-24 19:59:42.345 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Declared Method org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:42.345 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Locating method activate in class java.lang.Object
2023-01-24 19:59:42.346 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-01-24 19:59:42.346 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : activate method [activate] not found, ignoring
2023-01-24 19:59:42.347 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Set implementation object for component
2023-01-24 19:59:42.347 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : Changed state from satisfied to active
2023-01-24 19:59:42.348 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.4.1 (283)[org.openhab.io.homekit.internal.HomekitCommandExtension(377)] : dm Homekit tracking 1 SingleStatic added {org.openhab.io.homekit.Homekit}={port=9123, qrCode=X-HM://001408XXEMWLO, thermostatTargetModeHeat=Comfort, service.id=662, service.bundleid=283, service.scope=bundle, thermostatCurrentModeOff=Economy, thermostatCurrentModeHeating=Comfort, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, thermostatTargetModeOff=Economy, component.id=378, service.config.factory=false, setupId=MWLO, useOHmDNS=true, networkInterface=192.168.1.10, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]} (exit)
2023-01-24 19:59:43.859 [WARN ] [erver.impl.pairing.PairVerifyManager] - Unknown user: 805F739A-9902-41EA-815C-45B77AD8F0C6