2022-04-01 18:21:43.274 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : BundleComponentActivator : ComponentHolder created.
2022-04-01 18:21:43.279 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : BundleComponentActivator : ComponentHolder created.
2022-04-01 18:21:43.287 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : ConfigurableComponentHolder configuration updated for pid org.openhab.homekit with change count 13
2022-04-01 18:21:43.288 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.291 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[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]
2022-04-01 18:21:43.292 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component Services: scope=singleton, services=[org.openhab.core.io.console.extensions.ConsoleCommandExtension]
2022-04-01 18:21:43.294 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component Properties: {}
2022-04-01 18:21:43.306 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2022-04-01 18:21:43.307 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2022-04-01 18:21:43.322 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Component can not be activated since it is in state disabled
2022-04-01 18:21:43.323 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension] : Querying state disabled
2022-04-01 18:21:43.324 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Updating target filters
2022-04-01 18:21:43.326 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : No change in target property for dependency Homekit: currently registered: false
2022-04-01 18:21:43.327 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] :  No existing service listener to unregister for dependency Homekit
2022-04-01 18:21:43.339 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Setting target property for dependency Homekit to null
2022-04-01 18:21:43.340 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : New service tracker for Homekit, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.io.homekit.Homekit), initialReferenceFilter (objectClass=org.openhab.io.homekit.Homekit)
2022-04-01 18:21:43.341 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : dm Homekit tracker reset (closed)
2022-04-01 18:21:43.360 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : dm Homekit tracker opened
2022-04-01 18:21:43.363 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : registering service listener for dependency Homekit
2022-04-01 18:21:43.366 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Changed state from disabled to unsatisfiedReference
2022-04-01 18:21:43.369 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Component enabled
2022-04-01 18:21:43.370 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : ActivateInternal
2022-04-01 18:21:43.375 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.376 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.380 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Activating component from state unsatisfiedReference
2022-04-01 18:21:43.385 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.387 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.394 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Dependency not satisfied: Homekit
2022-04-01 18:21:43.395 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Not all dependencies satisfied, cannot activate
2022-04-01 18:21:43.397 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.398 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.402 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.403 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.405 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.412 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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
2022-04-01 18:21:43.415 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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]
2022-04-01 18:21:43.418 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : Component Services: scope=singleton, services=[org.openhab.io.homekit.Homekit]
2022-04-01 18:21:43.421 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[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}
2022-04-01 18:21:43.425 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2022-04-01 18:21:43.428 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2022-04-01 18:21:43.429 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : Component can not be activated since it is in state disabled
2022-04-01 18:21:43.447 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl] : Querying state disabled
2022-04-01 18:21:43.449 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Updating target filters
2022-04-01 18:21:43.450 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $000: currently registered: false
2022-04-01 18:21:43.452 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $000
2022-04-01 18:21:43.453 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $000 to null
2022-04-01 18:21:43.458 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.storage.StorageService), initialReferenceFilter (objectClass=org.openhab.core.storage.StorageService)
2022-04-01 18:21:43.459 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $000 tracker reset (closed)
2022-04-01 18:21:43.467 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.storage.StorageService}={service.id=341, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=231, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage} (enter)
2022-04-01 18:21:43.469 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.479 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.storage.StorageService}={service.id=341, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=231, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage} (exit)
2022-04-01 18:21:43.482 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $000 tracker opened
2022-04-01 18:21:43.485 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $000
2022-04-01 18:21:43.486 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $001: currently registered: false
2022-04-01 18:21:43.488 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $001
2022-04-01 18:21:43.499 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $001 to null
2022-04-01 18:21:43.511 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.items.ItemRegistry), initialReferenceFilter (objectClass=org.openhab.core.items.ItemRegistry)
2022-04-01 18:21:43.513 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $001 tracker reset (closed)
2022-04-01 18:21:43.518 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=190, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=32} (enter)
2022-04-01 18:21:43.521 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.523 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.items.ItemRegistry}={service.id=190, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=32} (exit)
2022-04-01 18:21:43.526 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $001 tracker opened
2022-04-01 18:21:43.528 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $001
2022-04-01 18:21:43.531 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $002: currently registered: false
2022-04-01 18:21:43.532 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $002
2022-04-01 18:21:43.535 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $002 to null
2022-04-01 18:21:43.539 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.net.NetworkAddressService), initialReferenceFilter (objectClass=org.openhab.core.net.NetworkAddressService)
2022-04-01 18:21:43.547 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $002 tracker reset (closed)
2022-04-01 18:21:43.550 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=199, service.bundleid=151, service.scope=bundle, service.config.category=system, service.pid=[org.openhab.network, org.openhab.network], service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, primaryAddress=192.168.111.46/24, component.id=50} (enter)
2022-04-01 18:21:43.551 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.552 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.net.NetworkAddressService}={service.id=199, service.bundleid=151, service.scope=bundle, service.config.category=system, service.pid=[org.openhab.network, org.openhab.network], service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, primaryAddress=192.168.111.46/24, component.id=50} (exit)
2022-04-01 18:21:43.554 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $002 tracker opened
2022-04-01 18:21:43.555 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $002
2022-04-01 18:21:43.556 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $003: currently registered: false
2022-04-01 18:21:43.557 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $003
2022-04-01 18:21:43.559 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $003 to null
2022-04-01 18:21:43.560 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : New service tracker for $003, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.items.MetadataRegistry), initialReferenceFilter (objectClass=org.openhab.core.items.MetadataRegistry)
2022-04-01 18:21:43.561 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $003 tracker reset (closed)
2022-04-01 18:21:43.564 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $003 tracking 4 SingleStatic added {org.openhab.core.items.MetadataRegistry}={service.id=189, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=37} (enter)
2022-04-01 18:21:43.565 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $003 tracking 4 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.566 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $003 tracking 4 SingleStatic added {org.openhab.core.items.MetadataRegistry}={service.id=189, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=37} (exit)
2022-04-01 18:21:43.567 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $003 tracker opened
2022-04-01 18:21:43.568 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $003
2022-04-01 18:21:43.569 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $004: currently registered: false
2022-04-01 18:21:43.570 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $004
2022-04-01 18:21:43.571 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $004 to null
2022-04-01 18:21:43.572 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : New service tracker for $004, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.cm.ConfigurationAdmin), initialReferenceFilter (objectClass=org.osgi.service.cm.ConfigurationAdmin)
2022-04-01 18:21:43.574 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $004 tracker reset (closed)
2022-04-01 18:21:43.576 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : 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)
2022-04-01 18:21:43.578 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $004 tracking 5 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.579 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : 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)
2022-04-01 18:21:43.580 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $004 tracker opened
2022-04-01 18:21:43.581 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $004
2022-04-01 18:21:43.582 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : No change in target property for dependency $005: currently registered: false
2022-04-01 18:21:43.583 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] :  No existing service listener to unregister for dependency $005
2022-04-01 18:21:43.586 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Setting target property for dependency $005 to null
2022-04-01 18:21:43.587 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : 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)
2022-04-01 18:21:43.589 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $005 tracker reset (closed)
2022-04-01 18:21:43.592 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $005 tracking 6 SingleStatic added {org.openhab.core.io.transport.mdns.MDNSClient}={service.id=307, service.bundleid=187, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=189} (enter)
2022-04-01 18:21:43.593 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $005 tracking 6 SingleStatic active: false trackerOpened: false optional: false
2022-04-01 18:21:43.594 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $005 tracking 6 SingleStatic added {org.openhab.core.io.transport.mdns.MDNSClient}={service.id=307, service.bundleid=187, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=189} (exit)
2022-04-01 18:21:43.595 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : dm $005 tracker opened
2022-04-01 18:21:43.597 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registering service listener for dependency $005
2022-04-01 18:21:43.598 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Changed state from disabled to unsatisfiedReference
2022-04-01 18:21:43.599 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Component enabled
2022-04-01 18:21:43.616 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : ActivateInternal
2022-04-01 18:21:43.617 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.619 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.620 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Activating component from state unsatisfiedReference
2022-04-01 18:21:43.621 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.622 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.623 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Changed state from unsatisfiedReference to satisfied
2022-04-01 18:21:43.624 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : registration change queue [registered]
2022-04-01 18:21:43.641 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : dm Homekit tracking 1 SingleStatic added {org.openhab.io.homekit.Homekit}={port=9124, qrCode=X-HM://001408XXEKHWI, service.id=577, service.bundleid=296, service.scope=bundle, name=openHAB, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, component.id=346, service.config.factory=false, setupId=KHWI, useOHmDNS=false, networkInterface=192.168.111.46, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]} (enter)
2022-04-01 18:21:43.642 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : ActivateInternal
2022-04-01 18:21:43.643 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.644 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.645 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Activating component from state unsatisfiedReference
2022-04-01 18:21:43.646 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.647 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state unsatisfiedReference
2022-04-01 18:21:43.648 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Changed state from unsatisfiedReference to satisfied
2022-04-01 18:21:43.649 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : registration change queue [registered]
2022-04-01 18:21:43.661 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Checking constructor public org.openhab.io.homekit.internal.HomekitCommandExtension()
2022-04-01 18:21:43.662 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Found constructor with 0 arguments : public org.openhab.io.homekit.internal.HomekitCommandExtension()
2022-04-01 18:21:43.663 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : getting bind: setHomekit
2022-04-01 18:21:43.664 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Locating method setHomekit in class org.openhab.io.homekit.internal.HomekitCommandExtension
2022-04-01 18:21:43.692 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : doFindMethod: Looking for method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit
2022-04-01 18:21:43.713 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit([interface org.osgi.framework.ServiceReference]) not found
2022-04-01 18:21:43.714 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit([interface org.osgi.service.component.ComponentServiceObjects]) not found
2022-04-01 18:21:43.732 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : getReferenceClass: Looking for interface class org.openhab.io.homekit.Homekit through loader of org.openhab.io.homekit.internal.HomekitCommandExtension
2022-04-01 18:21:43.733 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : getParameterClass: Found class org.openhab.io.homekit.Homekit
2022-04-01 18:21:43.735 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.io.homekit.Homekit
2022-04-01 18:21:43.736 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : doFindMethod: Found Method public void org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit(org.openhab.io.homekit.Homekit)
2022-04-01 18:21:43.743 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Found bind method: public void org.openhab.io.homekit.internal.HomekitCommandExtension.setHomekit(org.openhab.io.homekit.Homekit)
2022-04-01 18:21:43.781 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : 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,java.util.Map) throws java.io.IOException,java.security.InvalidAlgorithmParameterException
2022-04-01 18:21:43.784 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.openhab.core.storage.StorageService through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.791 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.openhab.core.storage.StorageService
2022-04-01 18:21:43.792 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.openhab.core.items.ItemRegistry through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.795 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.openhab.core.items.ItemRegistry
2022-04-01 18:21:43.797 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.openhab.core.net.NetworkAddressService through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.798 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.openhab.core.net.NetworkAddressService
2022-04-01 18:21:43.800 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.openhab.core.items.MetadataRegistry through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.801 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.openhab.core.items.MetadataRegistry
2022-04-01 18:21:43.803 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.osgi.service.cm.ConfigurationAdmin through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.804 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.osgi.service.cm.ConfigurationAdmin
2022-04-01 18:21:43.813 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getReferenceClass: Looking for interface class org.openhab.core.io.transport.mdns.MDNSClient through loader of org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:43.815 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getParameterClass: Found class org.openhab.core.io.transport.mdns.MDNSClient
2022-04-01 18:21:43.821 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Found constructor with 7 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,java.util.Map) throws java.io.IOException,java.security.InvalidAlgorithmParameterException
2022-04-01 18:21:43.822 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : This thread collected dependencies
2022-04-01 18:21:43.823 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getService (single component manager) dependencies collected.
2022-04-01 18:21:43.824 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state satisfied
2022-04-01 18:21:43.826 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Querying state satisfied
2022-04-01 18:21:43.827 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.storage.StorageService}={service.id=341, service.bundleid=211, service.scope=bundle, storage.format=json, service.config.label=Json Storage, component.name=org.openhab.core.storage.json, component.id=231, service.config.factory=false, service.config.category=system, service.pid=org.openhab.storage.json, service.config.description.uri=system:json_storage}] service: [null]]]
2022-04-01 18:21:43.829 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.items.ItemRegistry}={service.id=190, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.ItemRegistryImpl, component.id=32}] service: [null]]]
2022-04-01 18:21:43.831 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.net.NetworkAddressService}={service.id=199, service.bundleid=151, service.scope=bundle, service.config.category=system, service.pid=[org.openhab.network, org.openhab.network], service.config.label=Network Settings, component.name=org.openhab.core.net.NetUtil, service.config.description.uri=system:network, primaryAddress=192.168.111.46/24, component.id=50}] service: [null]]]
2022-04-01 18:21:43.832 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : For dependency $003, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.items.MetadataRegistry}={service.id=189, service.bundleid=151, service.scope=bundle, component.name=org.openhab.core.internal.items.MetadataRegistryImpl, component.id=37}] service: [null]]]
2022-04-01 18:21:43.833 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : 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]]]
2022-04-01 18:21:43.834 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : For dependency $005, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.mdns.MDNSClient}={service.id=307, service.bundleid=187, service.scope=bundle, component.name=org.openhab.core.io.transport.mdns.internal.MDNSClientImpl, component.id=189}] service: [null]]]
2022-04-01 18:21:45.039 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : getting activate: activate
2022-04-01 18:21:45.041 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Locating method activate in class org.openhab.io.homekit.internal.HomekitImpl
2022-04-01 18:21:45.042 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Declared Method org.openhab.io.homekit.internal.HomekitImpl.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-04-01 18:21:45.044 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Locating method activate in class java.lang.Object
2022-04-01 18:21:45.046 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-04-01 18:21:45.049 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : activate method [activate] not found, ignoring
2022-04-01 18:21:45.055 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Set implementation object for component
2022-04-01 18:21:45.056 [DEBUG] [nhab.io.homekit.internal.HomekitImpl] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitImpl(346)] : Changed state from satisfied to active
2022-04-01 18:21:45.060 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : This thread collected dependencies
2022-04-01 18:21:45.061 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : getService (single component manager) dependencies collected.
2022-04-01 18:21:45.062 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state satisfied
2022-04-01 18:21:45.063 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Querying state satisfied
2022-04-01 18:21:45.065 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : For dependency Homekit, optional: false; to bind: [[RefPair: ref: [{org.openhab.io.homekit.Homekit}={port=9124, qrCode=X-HM://001408XXEKHWI, service.id=577, service.bundleid=296, service.scope=bundle, name=openHAB, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, component.id=346, service.config.factory=false, setupId=KHWI, useOHmDNS=false, networkInterface=192.168.111.46, 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@1e4258f]]]
2022-04-01 18:21:45.067 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : invoking bind: setHomekit: parameters [org.openhab.io.homekit.internal.HomekitImpl]
2022-04-01 18:21:45.068 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : invoked bind: setHomekit
2022-04-01 18:21:45.069 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : getting activate: activate
2022-04-01 18:21:45.070 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Locating method activate in class org.openhab.io.homekit.internal.HomekitCommandExtension
2022-04-01 18:21:45.072 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Declared Method org.openhab.io.homekit.internal.HomekitCommandExtension.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-04-01 18:21:45.073 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Locating method activate in class org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension
2022-04-01 18:21:45.074 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Declared Method org.openhab.core.io.console.extensions.AbstractConsoleCommandExtension.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-04-01 18:21:45.076 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Locating method activate in class java.lang.Object
2022-04-01 18:21:45.080 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2022-04-01 18:21:45.082 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : activate method [activate] not found, ignoring
2022-04-01 18:21:45.082 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Set implementation object for component
2022-04-01 18:21:45.084 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : Changed state from satisfied to active
2022-04-01 18:21:45.087 [DEBUG] [kit.internal.HomekitCommandExtension] - bundle org.openhab.io.homekit:3.2.0 (296)[org.openhab.io.homekit.internal.HomekitCommandExtension(345)] : dm Homekit tracking 1 SingleStatic added {org.openhab.io.homekit.Homekit}={port=9124, qrCode=X-HM://001408XXEKHWI, service.id=577, service.bundleid=296, service.scope=bundle, name=openHAB, component.name=org.openhab.io.homekit.internal.HomekitImpl, service.config.label=HomeKit Integration, component.id=346, service.config.factory=false, setupId=KHWI, useOHmDNS=false, networkInterface=192.168.111.46, service.config.category=io, service.config.description.uri=io:homekit, service.pid=[org.openhab.homekit, org.openhab.homekit]} (exit)