EnOcean USB300 initialization fails with OH 4.0.0.M3 and OH 4.0.0.#3512

jimmbimm · June 25, 2023, 3:18pm

EnOcean USB300 gateway “trying to get bridge base id …” does not work anymore with OH 4.0.0.M3.

I tested it with OH 4.0.0.M3 as well as lastest snapshot.
Both show the error on a rpi4 with a fresh openHABian install and nothing but the EnOcean binding and the USB300 gateway.

Is there anybody who has the EnOcean USB300 gateway running with OH 4.0.0.M3?

jimmbimm · June 26, 2023, 6:31am

Also with a fresh install of openHABian 1.8 and OH 4.0.0.M3 as well as OH 4.0.0.#3512 EnOcean USB300 initializaiton fails.

2023-06-26 07:58:58.774 [INFO ] [org.openhab.core.Activator          ] - Starting openHAB 4.0.0 (build Build #3512)
2023-06-26 07:59:08.117 [INFO ] [nal.ScriptEngineFactoryBundleTracker] - All automation bundles ready.
2023-06-26 07:59:14.839 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2023-06-26 07:59:32.668 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2023-06-26 07:59:48.050 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2023-06-26 07:59:48.053 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : BundleComponentActivator : ComponentHolder created.
2023-06-26 07:59:48.057 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : BundleComponentActivator : ComponentHolder created.
2023-06-26 07:59:48.060 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.io.transport.serial.SerialPortManager, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2023-06-26 07:59:48.062 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created $001interface=org.openhab.core.thing.ThingManager, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2023-06-26 07:59:48.063 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created $002interface=org.openhab.core.thing.link.ItemChannelLinkRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=2
2023-06-26 07:59:48.064 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-06-26 07:59:48.065 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.enocean.internal.EnOceanHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.enocean]
2023-06-26 07:59:48.067 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2023-06-26 07:59:48.068 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-06-26 07:59:48.069 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-26 07:59:48.070 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-26 07:59:48.071 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component can not be activated since it is in state disabled
2023-06-26 07:59:48.073 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-26 07:59:48.074 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Updating target filters
2023-06-26 07:59:48.075 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : No change in target property for dependency $000: currently registered: false
2023-06-26 07:59:48.076 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] :  No existing service listener to unregister for dependency $000
2023-06-26 07:59:48.077 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Setting target property for dependency $000 to null
2023-06-26 07:59:48.079 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.serial.SerialPortManager), initialReferenceFilter (objectClass=org.openhab.core.io.transport.serial.SerialPortManager)
2023-06-26 07:59:48.080 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $000 tracker reset (closed)
2023-06-26 07:59:48.082 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=494, service.bundleid=244, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=309} (enter)
2023-06-26 07:59:48.083 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-06-26 07:59:48.084 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=494, service.bundleid=244, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=309} (exit)
2023-06-26 07:59:48.085 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $000 tracker opened
2023-06-26 07:59:48.086 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : registering service listener for dependency $000
2023-06-26 07:59:48.087 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : No change in target property for dependency $001: currently registered: false
2023-06-26 07:59:48.088 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] :  No existing service listener to unregister for dependency $001
2023-06-26 07:59:48.088 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Setting target property for dependency $001 to null
2023-06-26 07:59:48.089 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.ThingManager), initialReferenceFilter (objectClass=org.openhab.core.thing.ThingManager)
2023-06-26 07:59:48.091 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $001 tracker reset (closed)
2023-06-26 07:59:48.092 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=448, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=29} (enter)
2023-06-26 07:59:48.093 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-06-26 07:59:48.095 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=448, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=29} (exit)
2023-06-26 07:59:48.096 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $001 tracker opened
2023-06-26 07:59:48.097 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : registering service listener for dependency $001
2023-06-26 07:59:48.098 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : No change in target property for dependency $002: currently registered: false
2023-06-26 07:59:48.098 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] :  No existing service listener to unregister for dependency $002
2023-06-26 07:59:48.099 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Setting target property for dependency $002 to null
2023-06-26 07:59:48.100 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : New service tracker for $002, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.link.ItemChannelLinkRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.link.ItemChannelLinkRegistry)
2023-06-26 07:59:48.101 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $002 tracker reset (closed)
2023-06-26 07:59:48.103 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=361, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=40} (enter)
2023-06-26 07:59:48.104 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $002 tracking 3 SingleStatic active: false trackerOpened: false optional: false
2023-06-26 07:59:48.105 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $002 tracking 3 SingleStatic added {org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=361, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=40} (exit)
2023-06-26 07:59:48.106 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm $002 tracker opened
2023-06-26 07:59:48.107 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : registering service listener for dependency $002
2023-06-26 07:59:48.108 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.108 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-06-26 07:59:48.109 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-06-26 07:59:48.110 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-06-26 07:59:48.111 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm osgi.ds.satisfying.condition tracking 4 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-06-26 07:59:48.113 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm osgi.ds.satisfying.condition tracking 4 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-06-26 07:59:48.114 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : dm osgi.ds.satisfying.condition tracker opened
2023-06-26 07:59:48.115 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.115 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Changed state from disabled to unsatisfiedReference
2023-06-26 07:59:48.116 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Component enabled
2023-06-26 07:59:48.117 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : ActivateInternal
2023-06-26 07:59:48.118 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.119 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.120 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Activating component from state unsatisfiedReference
2023-06-26 07:59:48.121 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.122 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.123 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Changed state from unsatisfiedReference to satisfied
2023-06-26 07:59:48.123 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : registration change queue [registered]
2023-06-26 07:59:48.129 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Checking constructor public org.openhab.binding.enocean.internal.EnOceanHandlerFactory(org.openhab.core.io.transport.serial.SerialPortManager,org.openhab.core.thing.ThingManager,org.openhab.core.thing.link.ItemChannelLinkRegistry)
2023-06-26 07:59:48.131 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-26 07:59:48.131 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Found class org.openhab.core.io.transport.serial.SerialPortManager
2023-06-26 07:59:48.132 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.thing.ThingManager through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-26 07:59:48.133 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Found class org.openhab.core.thing.ThingManager
2023-06-26 07:59:48.134 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.thing.link.ItemChannelLinkRegistry through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-26 07:59:48.135 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getClassFromComponentClassLoader: Found class org.openhab.core.thing.link.ItemChannelLinkRegistry
2023-06-26 07:59:48.136 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Found constructor with 3 arguments : public org.openhab.binding.enocean.internal.EnOceanHandlerFactory(org.openhab.core.io.transport.serial.SerialPortManager,org.openhab.core.thing.ThingManager,org.openhab.core.thing.link.ItemChannelLinkRegistry)
2023-06-26 07:59:48.137 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : This thread collected dependencies
2023-06-26 07:59:48.138 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getService (single component manager) dependencies collected.
2023-06-26 07:59:48.139 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state satisfied
2023-06-26 07:59:48.140 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Querying state satisfied
2023-06-26 07:59:48.142 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=494, service.bundleid=244, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=309}] service: [null]]]
2023-06-26 07:59:48.143 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=448, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=29}] service: [null]]]
2023-06-26 07:59:48.144 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : For dependency $002, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=361, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=40}] service: [null]]]
2023-06-26 07:59:48.145 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-06-26 07:59:48.171 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : getting activate: activate
2023-06-26 07:59:48.172 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Locating method activate in class org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-26 07:59:48.174 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Declared Method org.openhab.binding.enocean.internal.EnOceanHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-26 07:59:48.175 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2023-06-26 07:59:48.176 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2023-06-26 07:59:48.177 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2023-06-26 07:59:48.178 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : invoked activate: activate
2023-06-26 07:59:48.179 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Set implementation object for component
2023-06-26 07:59:48.180 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(312)] : Changed state from satisfied to active
2023-06-26 07:59:48.188 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-06-26 07:59:48.189 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component created: DS=DS13, implementation=org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant]
2023-06-26 07:59:48.190 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.usbserial.UsbSerialDiscoveryParticipant]
2023-06-26 07:59:48.190 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-06-26 07:59:48.191 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-26 07:59:48.192 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-26 07:59:48.193 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component can not be activated since it is in state disabled
2023-06-26 07:59:48.193 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-26 07:59:48.194 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Updating target filters
2023-06-26 07:59:48.195 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.195 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-06-26 07:59:48.196 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-06-26 07:59:48.197 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-06-26 07:59:48.198 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-06-26 07:59:48.199 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-06-26 07:59:48.200 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : dm osgi.ds.satisfying.condition tracker opened
2023-06-26 07:59:48.201 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.202 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Changed state from disabled to unsatisfiedReference
2023-06-26 07:59:48.203 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Component enabled
2023-06-26 07:59:48.204 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : ActivateInternal
2023-06-26 07:59:48.205 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.205 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.206 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Activating component from state unsatisfiedReference
2023-06-26 07:59:48.207 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.208 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.209 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Changed state from unsatisfiedReference to satisfied
2023-06-26 07:59:48.210 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : registration change queue [registered]
2023-06-26 07:59:48.212 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Dependency Manager created $000interface=org.openhab.core.thing.type.ChannelTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2023-06-26 07:59:48.213 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-06-26 07:59:48.214 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component created: DS=DS14, implementation=org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory]
2023-06-26 07:59:48.214 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.profiles.ProfileFactory, org.openhab.core.thing.profiles.ProfileAdvisor, org.openhab.core.thing.profiles.ProfileTypeProvider]
2023-06-26 07:59:48.215 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2023-06-26 07:59:48.216 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-26 07:59:48.217 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-26 07:59:48.218 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component can not be activated since it is in state disabled
2023-06-26 07:59:48.219 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-26 07:59:48.219 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Updating target filters
2023-06-26 07:59:48.220 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : No change in target property for dependency $000: currently registered: false
2023-06-26 07:59:48.221 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] :  No existing service listener to unregister for dependency $000
2023-06-26 07:59:48.222 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Setting target property for dependency $000 to null
2023-06-26 07:59:48.223 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-26 07:59:48.224 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm $000 tracker reset (closed)
2023-06-26 07:59:48.225 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=156, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=45} (enter)
2023-06-26 07:59:48.226 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-06-26 07:59:48.228 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=156, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=45} (exit)
2023-06-26 07:59:48.228 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm $000 tracker opened
2023-06-26 07:59:48.229 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : registering service listener for dependency $000
2023-06-26 07:59:48.230 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.231 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2023-06-26 07:59:48.232 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2023-06-26 07:59:48.233 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2023-06-26 07:59:48.234 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2023-06-26 07:59:48.235 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2023-06-26 07:59:48.236 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : dm osgi.ds.satisfying.condition tracker opened
2023-06-26 07:59:48.237 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : registering service listener for dependency osgi.ds.satisfying.condition
2023-06-26 07:59:48.238 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Changed state from disabled to unsatisfiedReference
2023-06-26 07:59:48.238 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Component enabled
2023-06-26 07:59:48.239 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : ActivateInternal
2023-06-26 07:59:48.240 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.241 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.241 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Activating component from state unsatisfiedReference
2023-06-26 07:59:48.242 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.243 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state unsatisfiedReference
2023-06-26 07:59:48.243 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Changed state from unsatisfiedReference to satisfied
2023-06-26 07:59:48.244 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : registration change queue [registered]
2023-06-26 07:59:48.247 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Checking constructor public org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-26 07:59:48.248 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.thing.type.ChannelTypeRegistry through loader of org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory
2023-06-26 07:59:48.249 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : getClassFromComponentClassLoader: Found class org.openhab.core.thing.type.ChannelTypeRegistry
2023-06-26 07:59:48.250 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Found constructor with 1 arguments : public org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-26 07:59:48.251 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : This thread collected dependencies
2023-06-26 07:59:48.252 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : getService (single component manager) dependencies collected.
2023-06-26 07:59:48.253 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state satisfied
2023-06-26 07:59:48.253 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Querying state satisfied
2023-06-26 07:59:48.254 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=156, service.bundleid=211, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=45}] service: [null]]]
2023-06-26 07:59:48.255 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-06-26 07:59:48.257 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : getting activate: activate
2023-06-26 07:59:48.258 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Locating method activate in class org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory
2023-06-26 07:59:48.259 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Declared Method org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-26 07:59:48.260 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Locating method activate in class java.lang.Object
2023-06-26 07:59:48.261 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-26 07:59:48.261 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : activate method [activate] not found, ignoring
2023-06-26 07:59:48.262 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Set implementation object for component
2023-06-26 07:59:48.263 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(314)] : Changed state from satisfied to active
2023-06-26 07:59:48.318 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Checking constructor public org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant()
2023-06-26 07:59:48.319 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Found constructor with 0 arguments : public org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant()
2023-06-26 07:59:48.320 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : This thread collected dependencies
2023-06-26 07:59:48.321 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : getService (single component manager) dependencies collected.
2023-06-26 07:59:48.322 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state satisfied
2023-06-26 07:59:48.323 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Querying state satisfied
2023-06-26 07:59:48.324 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2023-06-26 07:59:48.326 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : getting activate: activate
2023-06-26 07:59:48.327 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Locating method activate in class org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant
2023-06-26 07:59:48.328 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Declared Method org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-26 07:59:48.329 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Locating method activate in class java.lang.Object
2023-06-26 07:59:48.330 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-26 07:59:48.331 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : activate method [activate] not found, ignoring
2023-06-26 07:59:48.332 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Set implementation object for component
2023-06-26 07:59:48.333 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:4.0.0.202306251455 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(313)] : Changed state from satisfied to active
==> /var/log/openhab/events.log <==
2023-06-26 07:59:48.186 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to UNINITIALIZED (NOT_YET_READY)
==> /var/log/openhab/openhab.log <==
2023-06-26 07:59:49.869 [INFO ] [nal.ScriptEngineFactoryBundleTracker] - All automation bundles ready.
2023-06-26 07:59:50.961 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2023-06-26 07:59:51.133 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
==> /var/log/openhab/events.log <==
2023-06-26 07:59:51.040 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from UNINITIALIZED (NOT_YET_READY) to INITIALIZING
2023-06-26 07:59:51.051 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from INITIALIZING to OFFLINE (CONFIGURATION_PENDING): trying to connect to gateway...
2023-06-26 07:59:51.061 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to connect to gateway... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
==> /var/log/openhab/openhab.log <==
2023-06-26 07:59:51.136 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-06-26 07:59:51.138 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-06-26 07:59:51.139 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request base id
2023-06-26 07:59:51.148 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 07:59:51.151 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 08
2023-06-26 07:59:51.153 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700838
2023-06-26 07:59:51.159 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - set postmaster mailboxes
2023-06-26 07:59:51.161 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-26 07:59:51.162 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request version info
2023-06-26 07:59:51.164 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 07:59:51.408 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type SMART_ACK_COMMAND, payload 0814
2023-06-26 07:59:51.410 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500020006C40814C4
2023-06-26 07:59:51.672 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 03
2023-06-26 07:59:51.673 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700309
==> /var/log/openhab/events.log <==
2023-06-26 07:59:51.140 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-06-26 07:59:51.142 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...
==> /var/log/openhab/openhab.log <==
2023-06-26 08:00:51.166 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - shutting down transceiver
2023-06-26 08:00:51.168 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Interrupt rx Thread
2023-06-26 08:00:51.170 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Closing the serial port
2023-06-26 08:00:51.207 [INFO ] [ernal.transceiver.EnOceanTransceiver] - Transceiver shutdown
2023-06-26 08:00:51.233 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-06-26 08:00:51.236 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-06-26 08:00:51.238 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-06-26 08:00:51.240 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request base id
2023-06-26 08:00:51.242 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:00:51.244 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 08
2023-06-26 08:00:51.245 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700838
2023-06-26 08:00:51.255 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - set postmaster mailboxes
2023-06-26 08:00:51.256 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-26 08:00:51.257 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request version info
2023-06-26 08:00:51.259 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:00:51.505 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type SMART_ACK_COMMAND, payload 0814
2023-06-26 08:00:51.507 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500020006C40814C4
2023-06-26 08:00:51.769 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 03
2023-06-26 08:00:51.771 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700309
==> /var/log/openhab/events.log <==
2023-06-26 08:00:51.212 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
2023-06-26 08:00:51.237 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-06-26 08:00:51.241 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...
==> /var/log/openhab/openhab.log <==
2023-06-26 08:01:51.260 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - shutting down transceiver
2023-06-26 08:01:51.262 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Interrupt rx Thread
2023-06-26 08:01:51.264 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Closing the serial port
2023-06-26 08:01:51.313 [INFO ] [ernal.transceiver.EnOceanTransceiver] - Transceiver shutdown
2023-06-26 08:01:51.341 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-06-26 08:01:51.346 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-06-26 08:01:51.348 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-06-26 08:01:51.351 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request base id
2023-06-26 08:01:51.353 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:01:51.354 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 08
2023-06-26 08:01:51.356 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700838
2023-06-26 08:01:51.367 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - set postmaster mailboxes
2023-06-26 08:01:51.368 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-26 08:01:51.369 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request version info
2023-06-26 08:01:51.370 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:01:51.617 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type SMART_ACK_COMMAND, payload 0814
2023-06-26 08:01:51.619 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500020006C40814C4
2023-06-26 08:01:51.880 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 03
2023-06-26 08:01:51.882 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700309
==> /var/log/openhab/events.log <==
2023-06-26 08:01:51.319 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
2023-06-26 08:01:51.346 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-06-26 08:01:51.352 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...
==> /var/log/openhab/openhab.log <==
2023-06-26 08:02:51.372 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - shutting down transceiver
2023-06-26 08:02:51.374 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Interrupt rx Thread
2023-06-26 08:02:51.375 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Closing the serial port
2023-06-26 08:02:51.417 [INFO ] [ernal.transceiver.EnOceanTransceiver] - Transceiver shutdown
2023-06-26 08:02:51.445 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-06-26 08:02:51.448 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-06-26 08:02:51.468 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-06-26 08:02:51.472 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request base id
2023-06-26 08:02:51.473 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:02:51.475 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 08
2023-06-26 08:02:51.476 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700838
2023-06-26 08:02:51.479 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - set postmaster mailboxes
2023-06-26 08:02:51.481 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-26 08:02:51.482 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request version info
2023-06-26 08:02:51.483 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-26 08:02:51.730 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type SMART_ACK_COMMAND, payload 0814
2023-06-26 08:02:51.731 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500020006C40814C4
2023-06-26 08:02:51.985 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 03
2023-06-26 08:02:51.987 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700309
==> /var/log/openhab/events.log <==
2023-06-26 08:02:51.421 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
2023-06-26 08:02:51.465 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-06-26 08:02:51.472 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...

dirkdirk · June 28, 2023, 7:35am

Hi,
Have you tried to install the serial feature via openhab-cli console?

feature:install openhab-transport-serial

This fixed the issue once for me at an older OH version.

jimmbimm · June 28, 2023, 6:53pm

Hi Dirk,

Thanks for your help.

Unfortunately this did not solve the problem.

When I downgrade to OH 3.4.4. everything works fine … see logs:

2023-06-28 20:26:58.470 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Berlin'.
2023-06-28 20:26:58.524 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'de_DE'.
2023-06-28 20:27:28.845 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2023-06-28 20:27:33.522 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2023-06-28 20:27:34.188 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2023-06-28 20:27:34.192 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : BundleComponentActivator : ComponentHolder created.
2023-06-28 20:27:34.196 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : BundleComponentActivator : ComponentHolder created.
2023-06-28 20:27:34.200 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created itemChannelLinkRegistryinterface=org.openhab.core.thing.link.ItemChannelLinkRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=itemChannelLinkRegistry, field-option=replace, collection-type=null, parameter=null
2023-06-28 20:27:34.201 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created serialPortManagerinterface=org.openhab.core.io.transport.serial.SerialPortManager, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=serialPortManager, field-option=replace, collection-type=null, parameter=null
2023-06-28 20:27:34.203 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Dependency Manager created thingManagerinterface=org.openhab.core.thing.ThingManager, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=thingManager, field-option=replace, collection-type=null, parameter=null
2023-06-28 20:27:34.205 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component created: DS=DS13, implementation=org.openhab.binding.enocean.internal.EnOceanHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.enocean]
2023-06-28 20:27:34.207 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2023-06-28 20:27:34.208 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component Properties: {}
2023-06-28 20:27:34.210 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-28 20:27:34.211 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-28 20:27:34.212 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Component can not be activated since it is in state disabled
2023-06-28 20:27:34.213 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory] : Querying state disabled
2023-06-28 20:27:34.215 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Updating target filters
2023-06-28 20:27:34.216 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : No change in target property for dependency itemChannelLinkRegistry: currently registered: false
2023-06-28 20:27:34.217 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] :  No existing service listener to unregister for dependency itemChannelLinkRegistry
2023-06-28 20:27:34.219 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Setting target property for dependency itemChannelLinkRegistry to null
2023-06-28 20:27:34.220 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : New service tracker for itemChannelLinkRegistry, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.link.ItemChannelLinkRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.link.ItemChannelLinkRegistry)
2023-06-28 20:27:34.221 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm itemChannelLinkRegistry tracker reset (closed)
2023-06-28 20:27:34.224 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm itemChannelLinkRegistry tracking 1 SingleStatic added {org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=382, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=253} (enter)
2023-06-28 20:27:34.226 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm itemChannelLinkRegistry tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-06-28 20:27:34.227 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm itemChannelLinkRegistry tracking 1 SingleStatic added {org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=382, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=253} (exit)
2023-06-28 20:27:34.229 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm itemChannelLinkRegistry tracker opened
2023-06-28 20:27:34.230 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : registering service listener for dependency itemChannelLinkRegistry
2023-06-28 20:27:34.231 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : No change in target property for dependency serialPortManager: currently registered: false
2023-06-28 20:27:34.232 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] :  No existing service listener to unregister for dependency serialPortManager
2023-06-28 20:27:34.233 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Setting target property for dependency serialPortManager to null
2023-06-28 20:27:34.234 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : New service tracker for serialPortManager, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.serial.SerialPortManager), initialReferenceFilter (objectClass=org.openhab.core.io.transport.serial.SerialPortManager)
2023-06-28 20:27:34.235 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm serialPortManager tracker reset (closed)
2023-06-28 20:27:34.237 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm serialPortManager tracker opened
2023-06-28 20:27:34.238 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : registering service listener for dependency serialPortManager
2023-06-28 20:27:34.240 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : No change in target property for dependency thingManager: currently registered: false
2023-06-28 20:27:34.241 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] :  No existing service listener to unregister for dependency thingManager
2023-06-28 20:27:34.242 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Setting target property for dependency thingManager to null
2023-06-28 20:27:34.243 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : New service tracker for thingManager, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.ThingManager), initialReferenceFilter (objectClass=org.openhab.core.thing.ThingManager)
2023-06-28 20:27:34.244 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm thingManager tracker reset (closed)
2023-06-28 20:27:34.247 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm thingManager tracking 2 SingleStatic added {org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=410, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=243} (enter)
2023-06-28 20:27:34.248 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm thingManager tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-06-28 20:27:34.250 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm thingManager tracking 2 SingleStatic added {org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=410, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=243} (exit)
2023-06-28 20:27:34.251 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm thingManager tracker opened
2023-06-28 20:27:34.252 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : registering service listener for dependency thingManager
2023-06-28 20:27:34.253 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Changed state from disabled to unsatisfiedReference
2023-06-28 20:27:34.254 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Component enabled
2023-06-28 20:27:34.255 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : ActivateInternal
2023-06-28 20:27:34.256 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.257 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.258 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Activating component from state unsatisfiedReference
2023-06-28 20:27:34.259 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.260 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.262 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Dependency not satisfied: serialPortManager
2023-06-28 20:27:34.262 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Not all dependencies satisfied, cannot activate
2023-06-28 20:27:34.264 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component created: DS=DS13, implementation=org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant]
2023-06-28 20:27:34.264 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.usbserial.UsbSerialDiscoveryParticipant]
2023-06-28 20:27:34.265 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component Properties: {}
2023-06-28 20:27:34.266 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-28 20:27:34.267 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-28 20:27:34.268 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Component can not be activated since it is in state disabled
2023-06-28 20:27:34.269 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant] : Querying state disabled
2023-06-28 20:27:34.270 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Updating target filters
2023-06-28 20:27:34.272 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Changed state from disabled to unsatisfiedReference
2023-06-28 20:27:34.273 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Component enabled
2023-06-28 20:27:34.274 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : ActivateInternal
2023-06-28 20:27:34.274 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.275 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.276 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Activating component from state unsatisfiedReference
2023-06-28 20:27:34.277 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.278 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.279 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Changed state from unsatisfiedReference to satisfied
2023-06-28 20:27:34.280 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : registration change queue [registered]
2023-06-28 20:27:34.282 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Dependency Manager created $000interface=org.openhab.core.thing.type.ChannelTypeRegistry, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2023-06-28 20:27:34.283 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component created: DS=DS14, implementation=org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory]
2023-06-28 20:27:34.284 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.profiles.ProfileFactory, org.openhab.core.thing.profiles.ProfileAdvisor, org.openhab.core.thing.profiles.ProfileTypeProvider]
2023-06-28 20:27:34.285 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component Properties: {}
2023-06-28 20:27:34.286 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-28 20:27:34.287 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-28 20:27:34.287 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Component can not be activated since it is in state disabled
2023-06-28 20:27:34.288 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory] : Querying state disabled
2023-06-28 20:27:34.289 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Updating target filters
2023-06-28 20:27:34.290 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : No change in target property for dependency $000: currently registered: false
2023-06-28 20:27:34.291 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] :  No existing service listener to unregister for dependency $000
2023-06-28 20:27:34.292 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Setting target property for dependency $000 to null
2023-06-28 20:27:34.293 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry), initialReferenceFilter (objectClass=org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-28 20:27:34.294 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : dm $000 tracker reset (closed)
2023-06-28 20:27:34.296 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=389, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=258} (enter)
2023-06-28 20:27:34.298 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-06-28 20:27:34.299 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=389, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=258} (exit)
2023-06-28 20:27:34.300 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : dm $000 tracker opened
2023-06-28 20:27:34.301 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : registering service listener for dependency $000
2023-06-28 20:27:34.302 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Changed state from disabled to unsatisfiedReference
2023-06-28 20:27:34.303 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Component enabled
2023-06-28 20:27:34.304 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : ActivateInternal
2023-06-28 20:27:34.305 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.305 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.306 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Activating component from state unsatisfiedReference
2023-06-28 20:27:34.307 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.308 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.309 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Changed state from unsatisfiedReference to satisfied
2023-06-28 20:27:34.310 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : registration change queue [registered]
2023-06-28 20:27:34.314 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Checking constructor public org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-28 20:27:34.315 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : getReferenceClass: Looking for interface class org.openhab.core.thing.type.ChannelTypeRegistry through loader of org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory
2023-06-28 20:27:34.316 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : getParameterClass: Found class org.openhab.core.thing.type.ChannelTypeRegistry
2023-06-28 20:27:34.317 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Found constructor with 1 arguments : public org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(org.openhab.core.thing.type.ChannelTypeRegistry)
2023-06-28 20:27:34.318 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : This thread collected dependencies
2023-06-28 20:27:34.319 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : getService (single component manager) dependencies collected.
2023-06-28 20:27:34.320 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state satisfied
2023-06-28 20:27:34.321 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Querying state satisfied
2023-06-28 20:27:34.322 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.type.ChannelTypeRegistry}={service.id=389, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.type.ChannelTypeRegistry, component.id=258}] service: [null]]]
2023-06-28 20:27:34.338 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : getting activate: activate
2023-06-28 20:27:34.339 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Locating method activate in class org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory
2023-06-28 20:27:34.341 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Declared Method org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-28 20:27:34.342 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Locating method activate in class java.lang.Object
2023-06-28 20:27:34.343 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-28 20:27:34.344 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : activate method [activate] not found, ignoring
2023-06-28 20:27:34.345 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Set implementation object for component
2023-06-28 20:27:34.345 [DEBUG] [ernal.profiles.EnOceanProfileFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.profiles.EnOceanProfileFactory(289)] : Changed state from satisfied to active
2023-06-28 20:27:34.370 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Checking constructor public org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant()
2023-06-28 20:27:34.372 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Found constructor with 0 arguments : public org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant()
2023-06-28 20:27:34.373 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : This thread collected dependencies
2023-06-28 20:27:34.374 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : getService (single component manager) dependencies collected.
2023-06-28 20:27:34.375 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state satisfied
2023-06-28 20:27:34.376 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Querying state satisfied
2023-06-28 20:27:34.378 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : getting activate: activate
2023-06-28 20:27:34.379 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Locating method activate in class org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant
2023-06-28 20:27:34.381 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Declared Method org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-28 20:27:34.382 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Locating method activate in class java.lang.Object
2023-06-28 20:27:34.383 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-28 20:27:34.384 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : activate method [activate] not found, ignoring
2023-06-28 20:27:34.385 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Set implementation object for component
2023-06-28 20:27:34.386 [DEBUG] [EnOceanUsbSerialDiscoveryParticipant] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.discovery.EnOceanUsbSerialDiscoveryParticipant(288)] : Changed state from satisfied to active
2023-06-28 20:27:34.484 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm serialPortManager tracking 3 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=483, service.bundleid=244, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=295} (enter)
2023-06-28 20:27:34.485 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : ActivateInternal
2023-06-28 20:27:34.486 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.487 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.488 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Activating component from state unsatisfiedReference
2023-06-28 20:27:34.489 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.491 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state unsatisfiedReference
2023-06-28 20:27:34.492 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Changed state from unsatisfiedReference to satisfied
2023-06-28 20:27:34.493 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : registration change queue [registered]
2023-06-28 20:27:34.500 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Checking constructor public org.openhab.binding.enocean.internal.EnOceanHandlerFactory()
2023-06-28 20:27:34.502 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Found constructor with 0 arguments : public org.openhab.binding.enocean.internal.EnOceanHandlerFactory()
2023-06-28 20:27:34.503 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getting field: itemChannelLinkRegistry
2023-06-28 20:27:34.504 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Locating field itemChannelLinkRegistry in class org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.506 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getReferenceClass: Looking for interface class org.openhab.core.thing.link.ItemChannelLinkRegistry through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.507 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getParameterClass: Found class org.openhab.core.thing.link.ItemChannelLinkRegistry
2023-06-28 20:27:34.508 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Found field: org.openhab.core.thing.link.ItemChannelLinkRegistry org.openhab.binding.enocean.internal.EnOceanHandlerFactory.itemChannelLinkRegistry
2023-06-28 20:27:34.510 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getting field: serialPortManager
2023-06-28 20:27:34.511 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Locating field serialPortManager in class org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.512 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getReferenceClass: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.513 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getParameterClass: Found class org.openhab.core.io.transport.serial.SerialPortManager
2023-06-28 20:27:34.515 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Found field: org.openhab.core.io.transport.serial.SerialPortManager org.openhab.binding.enocean.internal.EnOceanHandlerFactory.serialPortManager
2023-06-28 20:27:34.531 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getting field: thingManager
2023-06-28 20:27:34.532 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Locating field thingManager in class org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.533 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getReferenceClass: Looking for interface class org.openhab.core.thing.ThingManager through loader of org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.534 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getParameterClass: Found class org.openhab.core.thing.ThingManager
2023-06-28 20:27:34.536 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Found field: org.openhab.core.thing.ThingManager org.openhab.binding.enocean.internal.EnOceanHandlerFactory.thingManager
2023-06-28 20:27:34.537 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : This thread collected dependencies
2023-06-28 20:27:34.538 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getService (single component manager) dependencies collected.
2023-06-28 20:27:34.539 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state satisfied
2023-06-28 20:27:34.540 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Querying state satisfied
2023-06-28 20:27:34.542 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : For dependency itemChannelLinkRegistry, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.link.ItemChannelLinkRegistry}={service.id=382, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.link.ItemChannelLinkRegistry, component.id=253}] service: [org.openhab.core.thing.link.ItemChannelLinkRegistry@bd5a7f]]]
2023-06-28 20:27:34.543 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : For dependency serialPortManager, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=483, service.bundleid=244, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=295}] service: [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl@389387]]]
2023-06-28 20:27:34.544 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : For dependency thingManager, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.thing.ThingTypeMigrationService, org.openhab.core.thing.ThingManager}={service.id=410, service.bundleid=212, service.scope=bundle, component.name=org.openhab.core.thing.internal.ThingManagerImpl, component.id=243}] service: [org.openhab.core.thing.internal.ThingManagerImpl@106fb26]]]
2023-06-28 20:27:34.581 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : getting activate: activate
2023-06-28 20:27:34.582 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Locating method activate in class org.openhab.binding.enocean.internal.EnOceanHandlerFactory
2023-06-28 20:27:34.584 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Declared Method org.openhab.binding.enocean.internal.EnOceanHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-28 20:27:34.585 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2023-06-28 20:27:34.586 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2023-06-28 20:27:34.588 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2023-06-28 20:27:34.606 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : invoked activate: activate
2023-06-28 20:27:34.608 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Set implementation object for component
2023-06-28 20:27:34.608 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : Changed state from satisfied to active
2023-06-28 20:27:34.706 [DEBUG] [ocean.internal.EnOceanHandlerFactory] - bundle org.openhab.binding.enocean:3.4.4 (239)[org.openhab.binding.enocean.internal.EnOceanHandlerFactory(287)] : dm serialPortManager tracking 3 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=483, service.bundleid=244, service.scope=bundle, component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=295} (exit)
2023-06-28 20:27:34.806 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-06-28 20:27:34.809 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-06-28 20:27:34.811 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-06-28 20:27:34.812 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request base id
2023-06-28 20:27:34.819 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-28 20:27:34.822 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 08
2023-06-28 20:27:34.824 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700838
2023-06-28 20:27:34.829 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - set postmaster mailboxes
2023-06-28 20:27:34.831 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-28 20:27:34.832 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request version info
2023-06-28 20:27:34.833 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type COMMON_COMMAND with callback
2023-06-28 20:27:34.834 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Received Sync Byte
2023-06-28 20:27:34.836 [TRACE] [ernal.transceiver.EnOceanTransceiver] - >> Received header, data length 5 optional length 1 packet type 2
2023-06-28 20:27:34.840 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00FF9BB100 received
2023-06-28 20:27:34.860 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - received response for base id
2023-06-28 20:27:34.863 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Response handled
2023-06-28 20:27:35.078 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type SMART_ACK_COMMAND, payload 0814
2023-06-28 20:27:35.080 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500020006C40814C4
2023-06-28 20:27:35.090 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Received Sync Byte
2023-06-28 20:27:35.092 [TRACE] [ernal.transceiver.EnOceanTransceiver] - >> Received header, data length 1 optional length 0 packet type 2
2023-06-28 20:27:35.093 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2023-06-28 20:27:35.094 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - received response for postmaster mailboxes
2023-06-28 20:27:35.095 [DEBUG] [nternal.handler.EnOceanBridgeHandler] - request learned smack clients
2023-06-28 20:27:35.097 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type SMART_ACK_COMMAND with callback
2023-06-28 20:27:35.098 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Response handled
2023-06-28 20:27:35.342 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type COMMON_COMMAND, payload 03
2023-06-28 20:27:35.344 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Sending raw data: 5500010005700309
2023-06-28 20:27:35.362 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Received Sync Byte
2023-06-28 20:27:35.364 [TRACE] [ernal.transceiver.EnOceanTransceiver] - >> Received header, data length 33 optional length 0 packet type 2
2023-06-28 20:27:35.368 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00020F00000206090005943762454F0103474154455741594354524C0000000000 received
2023-06-28 20:27:35.370 [TRACE] [ernal.transceiver.EnOceanTransceiver] - Response handled
==> /var/log/openhab/events.log <==
2023-06-28 20:27:34.692 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-06-28 20:27:34.704 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from INITIALIZING to OFFLINE (CONFIGURATION_PENDING): trying to connect to gateway...
2023-06-28 20:27:34.714 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to connect to gateway... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
2023-06-28 20:27:34.809 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-06-28 20:27:34.813 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...
2023-06-28 20:27:34.863 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FT50A9GZ' changed from OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id... to ONLINE

I don’t know whether it means anything, but with OH 4.0.0.M3 the binding creates component “DS=DS14” at the beginning (see log in my previous post), whereas with OH 3.4.4 the binding starts with creating a component “DS=DS13” and a component “DS=DS14” afterwards.

Does anybody have an idea what is going wrong with OH 4.0.0.M3 here?

JensD · July 24, 2023, 3:55pm

Hello @jimmbimm
Could you solve the problem?
I’m stuck with the same on official OH4

jimmbimm · July 24, 2023, 4:31pm

Hello @JensD

No, unfortunately not … I posted it already in the OH4 release thread …

Maybe the problem is not related to the EnOcean bundle but to
org.openhab.core.io.transport.serial or
org.openhab.core.io.transport.serial.rxtx or
org.openhab.core.io.transport.serial.rxtx.rfc2217

I posted log files, since I am not skilled engough to find any clue in there.

Sadly, OH4 is blocked for me with EnOcean not working.

JensD · July 24, 2023, 4:33pm

Thanks for your update @jimmbimm
Seems we are not the only ones as there is an open issue on this in Github: [enocean] USB 300 cannot be set up in OH4 · Issue #15181 · openhab/openhab-addons · GitHub

I don’t think it a problem with the serial I/O, when I configure a wrong port it’s immediately detected.
But I will check on my side if there is anything.

jimmbimm · July 24, 2023, 4:36pm

ahh, great … thanks

fnu · July 24, 2023, 9:32pm

Hey, I have the same issue with my enoceanpi addon in my RaspberryPi 4, I needed to find out after openhabian provided openhab 4 as release packages … on my system.

Bridge is properly initialized by OS, serial settings for RPi remained the same:

[    1.570848] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 35, base_baud = 0) is a PL011 rev2

With openhab 4 packages I get same messages, looping:

==> /var/log/openhab/openhab.log <==
2023-07-24 23:11:52.894 [INFO ] [ernal.transceiver.EnOceanTransceiver] - Transceiver shutdown
2023-07-24 23:11:52.904 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-07-24 23:11:52.908 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-07-24 23:11:52.909 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running

==> /var/log/openhab/events.log <==
2023-07-24 23:11:52.896 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:enoceanpi' changed from OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id... to OFFLINE (CONFIGURATION_PENDING): opening serial port...
2023-07-24 23:11:52.908 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:enoceanpi' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-07-24 23:11:52.911 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:enoceanpi' changed from OFFLINE (CONFIGURATION_PENDING): starting rx thread... to OFFLINE (CONFIGURATION_PENDING): trying to get bridge base id...

Thanks for killing my relatively stable setup on Monday evening with that package move, I wanted to decide myself when to update to OH4 …

Don’t know if I can easily switch back to OH3 packages, as I needed to modify runtime.cfg comprehensive for OH4 …

Mahoni · July 24, 2023, 10:21pm

Hello

same here

I recently updated my system to Openhab 4.0.0 Release Build.

I connected an EnOcean USB 300 stick, and the bridge, as a Thing, has always worked flawlessly before the update. After the update, I received the following error message:

Status:
OFFLINE
CONFIGURATION_PENDING
trying to get bridge base id…

The log filtered for EnOcean looks like this:

2023-07-24 23:45:50.819 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EnOceanBridgeHandler tried updating the thing status although the handler was already disposed.
2023-07-24 23:45:41.967 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'enocean:bridge:FTXMJQ88' changed from OFFLINE (CONFIGURATION_PENDING): opening serial port... to OFFLINE (CONFIGURATION_PENDING): starting rx thread...
2023-07-24 23:45:41.967 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-07-24 23:45:41.968 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running

The configuration is set to /dev/ttyUSB0, although it seems that the stick is connected to ttyUSB1. The output of DMESG | grep usb is:

[    0.122569] usbcore: registered new interface driver usbfs
[    0.122644] usbcore: registered new interface driver hub
[    0.122723] usbcore: registered new device driver usb
[    0.123060] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.123286] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    1.486862] usbcore: registered new interface driver r8152
[    1.486949] usbcore: registered new interface driver lan78xx
[    1.487023] usbcore: registered new interface driver smsc95xx
[    1.535531] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[    1.535567] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.535595] usb usb1: Product: xHCI Host Controller
[    1.535617] usb usb1: Manufacturer: Linux 6.1.21-v8+ xhci-hcd
[    1.535640] usb usb1: SerialNumber: 0000:01:00.0
[    1.537449] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01
[    1.537485] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.537541] usb usb2: Product: xHCI Host Controller
[    1.537565] usb usb2: Manufacturer: Linux 6.1.21-v8+ xhci-hcd
[    1.537587] usb usb2: SerialNumber: 0000:01:00.0
[    1.540987] usbcore: registered new interface driver uas
[    1.541106] usbcore: registered new interface driver usb-storage
[    1.552085] usbcore: registered new interface driver usbhid
[    1.552110] usbhid: USB HID core driver
[    1.789671] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.940200] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.940260] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.940288] usb 1-1: Product: USB2.0 Hub
[    2.237595] usb 1-1.1: new full-speed USB device number 3 using xhci_hcd
[    2.345614] usb 1-1.1: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00
[    2.345662] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.345691] usb 1-1.1: Product: Sonoff Zigbee 3.0 USB Dongle Plus
[    2.345715] usb 1-1.1: Manufacturer: ITead
[    2.345736] usb 1-1.1: SerialNumber: 166c26c30712ec1186db21c7bd930c07
[    2.433574] usb 1-1.2: new high-speed USB device number 4 using xhci_hcd
[    2.534248] usb 1-1.2: New USB device found, idVendor=214b, idProduct=7000, bcdDevice= 1.00
[    2.534311] usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.534341] usb 1-1.2: Product: USB2.0 HUB
[    2.833618] usb 1-1.2.3: new full-speed USB device number 5 using xhci_hcd
[    2.963706] usb 1-1.2.3: New USB device found, idVendor=0403, idProduct=6001, bcdDevice= 6.00
[    2.963763] usb 1-1.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.963793] usb 1-1.2.3: Product: EnOcean USB 300 DB
[    2.963816] usb 1-1.2.3: Manufacturer: EnOcean GmbH
[    2.963838] usb 1-1.2.3: SerialNumber: FTXMJQ88
[    3.057592] usb 1-1.2.4: new high-speed USB device number 6 using xhci_hcd
[    3.166084] usb 1-1.2.4: New USB device found, idVendor=1908, idProduct=0226, bcdDevice= 1.11
[    3.166140] usb 1-1.2.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.168497] usb-storage 1-1.2.4:1.0: USB Mass Storage device detected
[    3.169259] scsi host0: usb-storage 1-1.2.4:1.0
[    5.658712] usbcore: registered new interface driver usbserial_generic
[    5.658858] usbserial: USB Serial support registered for generic
[    5.676954] usbcore: registered new interface driver cp210x
[    5.677102] usbserial: USB Serial support registered for cp210x
[    5.693602] usb 1-1.1: cp210x converter now attached to ttyUSB0
[    5.769491] usbcore: registered new interface driver ftdi_sio
[    5.769728] usbserial: USB Serial support registered for FTDI USB Serial Device
[    5.770219] usb 1-1.2.3: Detected FT232R
[    6.190333] usb 1-1.2.3: FTDI USB Serial Device converter now attached to ttyUSB1
[    6.333001] usbcore: registered new interface driver brcmfmac

When I try to configure the bridge to use ttyUSB1, I get the error message:

OFFLINE
CONFIGURATION_ERROR
Port could not be found[/quote]

Regarding permissions, as a Linux-noob, they seem fine to me:

openhabian@openhabian:/dev $ ls -l ttyUSB*
crw-rw---- 1 root dialout 188, 0 24. Jul 23:53 ttyUSB0
crw-rw---- 1 root dialout 188, 1 24. Jul 23:36 ttyUSB1

and

openhabian@openhabian:/dev $ groups
openhab adm tty dialout cdrom sudo audio video plugdev games users input render netdev bluetooth mosquitto gpio i2c spi openhabian

I’m confused - does anyone have a solution or an approach to resolve this issue?

Best regards and thank you very much,

Stefan

fnu · July 24, 2023, 11:15pm

Hi there,

some more investigation:

group membership and posix rights are correct:

openhabian@openhabian:~ $ id openhab
uid=110(openhab) gid=114(openhab) Gruppen=114(openhab),5(tty),20(dialout),29(audio),112(bluetooth),997(gpio)

openhabian@openhabian:~ $ ll /dev/ttyAMA0
crw-rw---- 1 root dialout 204, 64 25. Jul 01:04 /dev/ttyAMA0

/var/log/syslog shows proper OS initialization of EnOceanPi:

Jul 24 22:30:30 openhabian kernel: [    1.570932] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 35, base_baud = 0) is a PL011 rev2

I even added that to /etc/default/openhab, to give it a try, was never needed in the past:

EXTRA_JAVA_OPTS="-Dgnu.io.rxtx.SerialPorts=/dev/ttyAMA0"

Now I see additional message looping with the other ones above mentioned:

==> /var/log/openhab/openhab.log <==
2023-07-25 00:56:21.518 [WARN ] [erial.internal.SerialPortManagerImpl] - No SerialPortProvider found for: /dev/ttyAMA0

And I gathered feature information from openhab-cli:

openhab> feature:list | grep serial
openhab-core-io-transport-serial-javacomm         │ 4.0.0            │          │ Uninstalled │ distro-4.0.0             │
openhab-transport-serial                          │ 4.0.0            │          │ Started     │ distro-4.0.0             │ Serial Transport
openhab.tp-serial-javacomm                        │ 4.0.0            │          │ Uninstalled │ distro-4.0.0             │
openhab.tp-serial-rxtx                            │ 4.0.0            │          │ Started     │ distro-4.0.0             │

On top I cannot go back to OH3.4.4, its not anymore offered from repository. I really thank whom ever killing my running setup …

dalgwen · July 25, 2023, 7:15am

Same here,
I use the enocean bridge over a rfc2217 connection, with ser2net on the server side.

trying to get bridge base id... on GUI.

And in logs :

2023-07-25 09:11:52.068 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver initialized
2023-07-25 09:11:52.068 [INFO ] [ernal.transceiver.EnOceanTransceiver] - EnOceanSerialTransceiver RX thread started
2023-07-25 09:11:52.069 [INFO ] [nternal.handler.EnOceanBridgeHandler] - EnOceanSerialTransceiver RX thread up and running
2023-07-25 09:12:52.069 [INFO ] [ernal.transceiver.EnOceanTransceiver] - Transceiver shutdown

I know it’s not convenient, but please restrain. Maitainers are volunteers.

dirkdirk · July 25, 2023, 7:25am

I had the same problem in OH3 some time ago. I was able to fix it with this command via console:

feature:install openhab-transport-serial

The feature was already installed and active, but the renewed installation fixed it.
Maybe this will help, if not I need to wait before I update my setup to OH4.
Thanks for bringing this issue up.

fnu · July 25, 2023, 7:31am

@dirkdirk

Just for my case, you can clearly read in my post above, that prereq is shown as installed & started in my setup, forced updated to OH4 … in OH3 I never had any issue for years with that communication.

Who knows if not exactly that is actually broken in OH4 …

fnu · July 25, 2023, 7:49am

I’m active since 25 years in open source world, maintaining packages for Ubuntu & Debian, also as volunteer. First and only rule, never ever release any package as stable release if it is not 110% bullet proof working.

I appreciate all the work of all OH maintainers, but somebody has decided to force openhabian into OH4, without proofing EnOceanPi bridges 100% working after upgrade …

dirkdirk · July 25, 2023, 8:27am

I use the openocean file instead of the openhab binding. Worked always better for me.
So I compiled now the latest openocean files against 4.0.0.

Maybe someone want to try if there is the same issue wtih the bridge?
Just uninstall the official binding and use the jar file as addon.

Openocean for 4.0.0.

fnu · July 25, 2023, 9:16am

@dirkdirk

Thanks, good move, I may give it a try, but I’m kind of in doubt its (just) the EnOcean binding …

hmerk · July 25, 2023, 9:27am

Any proof for that ? Other bindings using serial interface (Zwave, Zigbee e.g.) are running fine.

dalgwen · July 25, 2023, 9:30am

It’s seems to be the EnOcean binding.
I tested several build against openHAB 4.0.0 and the one introducing the issue seems to be this one
I will try to pinpoint the issue more accurately.

hmerk · July 25, 2023, 9:40am

So SNAPSHOT versions of the binding before merging the PR you mentioned are working ?
@Kai shouldn’t we revert that PR within a bugfix release ?