Sonoff Zigbee Dongle Modell E no longer available after restart

Add-ons Bindings
1

Hello,
my Sonoff Zigbee Dongle Model E disappears when I restart it and I get the following error message:

2024-01-17 10:24:58.075 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NoClassDefFoundError: Could not initialize class com.thoughtworks.xstream.converters.collections.TreeSetConverter$Reflections
	at com.thoughtworks.xstream.converters.collections.TreeSetConverter.unmarshal(TreeSetConverter.java:62) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convert(TreeUnmarshaller.java:74) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractReferenceUnmarshaller.convert(AbstractReferenceUnmarshaller.java:72) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:68) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshallField(AbstractReflectionConverter.java:499) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.doUnmarshal(AbstractReflectionConverter.java:425) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshal(AbstractReflectionConverter.java:277) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convert(TreeUnmarshaller.java:74) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractReferenceUnmarshaller.convert(AbstractReferenceUnmarshaller.java:72) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:68) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:52) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.start(TreeUnmarshaller.java:136) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractTreeMarshallingStrategy.unmarshal(AbstractTreeMarshallingStrategy.java:32) ~[?:?]
	at com.thoughtworks.xstream.XStream.unmarshal(XStream.java:1464) ~[?:?]
	at com.thoughtworks.xstream.XStream.unmarshal(XStream.java:1441) ~[?:?]
	at com.thoughtworks.xstream.XStream.fromXML(XStream.java:1321) ~[?:?]
	at org.openhab.binding.zigbee.internal.ZigBeeDataStore.readNode(ZigBeeDataStore.java:134) ~[?:?]
	at com.zsmartsystems.zigbee.database.ZigBeeNetworkDatabaseManager.startup(ZigBeeNetworkDatabaseManager.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.initialize(ZigBeeNetworkManager.java:416) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:431) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:557) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.lang.ExceptionInInitializerError: Exception java.lang.reflect.InaccessibleObjectException: Unable to make field private transient java.util.NavigableMap java.util.TreeSet.m accessible: module java.base does not "opens java.util" to unnamed module @33264017 [in thread "OH-thingHandler-3"]
	at java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:354) ~[?:?]
	at java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:297) ~[?:?]
	at java.lang.reflect.Field.checkCanSetAccessible(Field.java:178) ~[?:?]
	at java.lang.reflect.Field.setAccessible(Field.java:172) ~[?:?]
	at com.thoughtworks.xstream.core.util.Fields.locate(Fields.java:40) ~[?:?]
	at com.thoughtworks.xstream.converters.collections.TreeSetConverter$Reflections.<clinit>(TreeSetConverter.java:135) ~[?:?]
	... 27 more

After a restart it no longer goes online, only deleting and re-creating the Coordinator Thing helps; until the next restart.
I have already deleted the binding and reinstalled it, unfortunately that doesn’t help. Unfortunately the DEBUG log doesn’t help me either:

2024-01-17 10:24:57.050 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.1.1 (305)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(367)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=1084, service.bundleid=310, service.scope=singleton} (enter)
2024-01-17 10:24:57.050 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.1.1 (305)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(367)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic already active, binding {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=1084, service.bundleid=310, service.scope=singleton}
2024-01-17 10:24:57.051 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.1.1 (305)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(367)] : invoking bind: addZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2024-01-17 10:24:57.051 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.1.1 (305)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(367)] : invoked bind: addZigBeeCoordinatorHandler
2024-01-17 10:24:57.051 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.1.1 (305)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(367)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=1084, service.bundleid=310, service.scope=singleton} (exit)
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:ZigbeeDongle].
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 2037
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 682844A80C922E8D
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 4EBE08FDA6442E662BC7D765EFA867FA
2024-01-17 10:24:57.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2024-01-17 10:24:57.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
2024-01-17 10:24:57.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 4EBE08FDA6442E662BC7D765EFA867FA
2024-01-17 10:24:57.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 4EBE08FDA6442E662BC7D765EFA867FA
2024-01-17 10:24:57.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2024-01-17 10:24:57.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2024-01-17 10:24:57.058 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-01-17 10:24:57.059 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM4' PAN:7f5, EPAN:682844A80C922E8D, Channel:11
2024-01-17 10:24:57.059 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2024-01-17 10:24:57.059 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-01-17 10:24:58.061 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-01-17 10:24:58.061 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-01-17 10:24:58.063 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING

Does anyone know this problem and has a solution for it?

Thank you very much for your ideas…

2

Hi, First let me say welcome to the Openhab community!
Normally when you post with a issues there is a little form that you fill out that will answer most of the basic questions.
However, since you pretty much put all that in your profile, we can see what we can do to help but still have a few more questions unfortunately.
I see you are running windows 11.
I see you are on Openhab 4.1.1 stable.
and have ZULU java 17 installed.
Did you let the java installer do your java install with defaults and allowed it to update your environment variables? Did you confirm your environment variables are correct (path and Java_Home in system Variables not user) and you do have a Java_home entry that is important.
Did you do the install with administrator permissions?
Are you using a direct USB port from your machine or do you have it plugged into a hub extension or a USB cord extender?
Is your Openhab running as a manually on your profile by launching (double clicking) start.bat in the Openhab folder or did you install it as a windows service? If you are running it as a service try removing it from a service and running it manually.
Do you have any other USB devices that are also plugged in?
Did you install the windows WHQL driver from sonoff?
Did you try disabling any anti-virus or at least excluding your zigbee device?
With these few pieces of info, we can rule out the most likely and common issues folks running Openhab on windows may see.
Usually if it works fine till you restart the OS it is an issue with com port assignment conflict. Or driver related.
I run a Windows 11 workstation box as my test system and have not had any issues with the Openhab 4.1.1 version I also do not recall any other reports of what you are seeing either. So, with all that being said we need to look at things outside of Openhab first and eliminate as many of those items as we can.
edit: one other question did you check the firmware version and make sure you are on the latest supported version for that dongle? I also run the exact same dongle and I had to update mine when I got it but that was a while (couple years) back.

3

Hello,

Unfortunately I couldn’t find the form to report a problem, where is it?
I’ve been using Openhab for many years, I think since Openhab 2.1. Now I wanted to expand the system with Zigbee and bought the Sonoff dongle.
Here are the answers to the missing information:

  • The Zulu Package was installed as administrator, the environment variables are present.
  • The dongle is plugged directly into the USB port, so there is only a memory stick left.
  • Openhab starts automatically as a Windows service.
  • The current driver is the WHQL driver from Sonoff, the device is called “USB-Enhanced-SERIAL CH9102” specified in the device manager
  • I have tested all available firmware versions, the behavior occurs with all versions. (Source: https://github.com/itead/Sonoff_Zigbee_Dongle_Firmware)

News:
If I stop the Windows Openhab service and start it manually with start.bat, the dongle is recognized and goes online. I recently found this thread: https://community.openhab.org/t/openhab-4-windows-issue/149765
I then checked the information from this post https://community.openhab.org/t/openhab-4-windows-issue/149765/9?, but my karaf.bat contains exactly the line as it appears on GutHub under the “config” link is specified. I wasn’t sure and completely reinstalled Windows Openhab service by first deleting it according to the documentation and then completely rebuilding it. That means I did the “wrapper procedure” from scratch.

I hope we can find the problem now.

Thank you, Alex

4

Hi,
Awesome that you have been running OpenHab for so long and only now needed to actually create an account in the Forum and ask a Question.
for the template info I was mentioning it is basically everything discussed here.

But we are past all that now.
So, you determined that if you remove it from running as a service and launch it manually without any changes everything works fine.
Good so that brings us forward.
If you add it back as a service and reboot but have the service set to manual and after reboot and log in you then start the service does it work? If it does can you then try setting the service to start as automatic(delayed start) and retry the reboot.

5

I always try to get the necessary information from the documentation and the forum first. This has helped so far, but I’ve been dealing with this problem for 3 weeks now and don’t know what to do next… :grimacing:

Even if I start the service manually it doesn’t work. Then it can only be due to an entry in the OpenHAB-wrapper.config, right? Here is the content:

#*******************************************************

# openHAB-wrapper.conf for Windows Service Installation

#*******************************************************

# openHAB installation dir (Adapt this first setting to your system)

set.default.OPENHAB_HOME=C:\openHAB3

# Wrapper Properties

set.default.OPENHAB_CONF=%OPENHAB_HOME%\conf

set.default.OPENHAB_RUNTIME=%OPENHAB_HOME%\runtime

set.default.OPENHAB_USERDATA=%OPENHAB_HOME%\userdata

set.default.OPENHAB_LOGDIR=%OPENHAB_USERDATA%\logs

set.default.KARAF_LOG=%OPENHAB_USERDATA%\logs

set.default.KARAF_HOME=%OPENHAB_RUNTIME%

set.default.KARAF_BASE=%OPENHAB_USERDATA%

set.default.KARAF_DATA=%OPENHAB_USERDATA%

set.default.KARAF_ETC=%OPENHAB_USERDATA%\etc

set.default.PATH=%PATH%;%KARAF_BASE%\lib;%KARAF_HOME%\lib

# Java Application

wrapper.working.dir=%KARAF_BASE%

wrapper.java.command=%JAVA_HOME%\bin\java

wrapper.java.mainclass=org.apache.karaf.wrapper.internal.service.Main

wrapper.java.classpath.1=%KARAF_HOME%\lib\boot\*.jar

wrapper.java.classpath.2=%KARAF_DATA%\lib\wrapper\*.jar

wrapper.java.classpath.3=%KARAF_HOME%\lib\jdk9plus\*.jar

wrapper.java.classpath.4=%KARAF_HOME%\lib\endorsed\*.jar

wrapper.java.library.path.1=%KARAF_DATA%\lib\wrapper\

# Java Parameters

wrapper.java.additional.1=-Dkaraf.home="%KARAF_HOME%"

wrapper.java.additional.2=-Dkaraf.base="%KARAF_BASE%"

wrapper.java.additional.3=-Dkaraf.data="%KARAF_DATA%"

wrapper.java.additional.4=-Dkaraf.etc="%KARAF_ETC%"

wrapper.java.additional.5=-Dcom.sun.management.jmxremote

wrapper.java.additional.6=-Dkaraf.startLocalConsole=false

wrapper.java.additional.7=-Dkaraf.startRemoteShell=true

wrapper.java.additional.8=-Dopenhab.home="%OPENHAB_HOME%"

wrapper.java.additional.9=-Dopenhab.conf="%OPENHAB_HOME%\conf"

wrapper.java.additional.10=-Dopenhab.runtime="%OPENHAB_HOME%\runtime"

wrapper.java.additional.11=-Dopenhab.userdata="%OPENHAB_HOME%\userdata"

wrapper.java.additional.12=-Dopenhab.logdir="%OPENHAB_USERDATA%\logs"

wrapper.java.additional.13=-Dfelix.cm.dir="%OPENHAB_HOME%\userdata\config"

wrapper.java.additional.14=-Dorg.osgi.service.http.port=8080

wrapper.java.additional.15=-Dorg.osgi.service.http.port.secure=8443

wrapper.java.additional.16=-Djava.util.logging.config.file="%KARAF_ETC%\java.util.logging.properties"

wrapper.java.additional.17=-Dkaraf.logs="%OPENHAB_LOGDIR%"

wrapper.java.additional.18=-Dfile.encoding=UTF-8

wrapper.java.maxmemory=512

# Wrapper Logging Properties

wrapper.console.format=PM

wrapper.console.loglevel=INFO

wrapper.logfile=%OPENHAB_USERDATA%\logs\wrapper.log

wrapper.logfile.format=LPTM

wrapper.logfile.loglevel=INFO

wrapper.logfile.maxsize=10m

wrapper.logfile.maxfiles=5

wrapper.syslog.loglevel=NONE

# Wrapper Windows Properties

wrapper.console.title=openHAB

wrapper.ntservice.name=openHAB

wrapper.ntservice.displayname=openHAB

wrapper.ntservice.description=openHAB Service

wrapper.ntservice.dependency.1=

wrapper.ntservice.starttype=AUTO_START

wrapper.ntservice.interactive=false

Please don’t be surprised, my installation folder is really called Openhab3, I was too lazy to change it…

6

Hi,
No concern with folder naming as long as it is the correct directory as your openhab install it will not matter.
I reviewed your wrapper.conf file and other then the jvm memory setting it appears to be identical to my working test box so as soon as I can find a few minutes spare time I will wipe out my testbox and reload windows and the openhab install on this test system and redo it all from scratch and see if I can repo your issue. However, so far testing with my existing build that is also a upgrade chain from 3.1 all the way to 4.1.1 it seems fine and I have not been able to see the error you do when I reboot and my Sonoff zigbee dongle E is working fine on this test system when running openhab as a service. It may take me a day or so to do it seems this thing they call work is interfering with my play time.

7

OK, thank you very much for taking the time to solve my problem! I’m looking forward to your results.

8

Hello,

I found out something else. An xml file for the coordinator and for a switchable socket is stored in the folder c:\Openhab3\userdata\zigbee\zigbee_coordinator_ember_ZigbeeDongle when I activate the dongle. If I switch the dongle off and on again, it doesn’t go online again and the error message appears in the log. If I delete the XML files before activating the dongle, it works.

Before I figured this out, I backed up Openhab this morning and completely reinstalled JAVA and Openhab. Without success.

I suspect that the XML files may be incorrect, I have now deactivated all Zigbee participants except for the coordinator. Here is the content of the coordinator’s XML file:

<ZigBeeNode>
  <ieeeAddress>E0798DFFFEC11166</ieeeAddress>
  <networkAddress>0</networkAddress>
  <nodeDescriptor>
    <apsFlags>0</apsFlags>
    <bufferSize>82</bufferSize>
    <complexDescriptorAvailable>false</complexDescriptorAvailable>
    <manufacturerCode>43981</manufacturerCode>
    <logicalType>COORDINATOR</logicalType>
    <serverCapabilities class="sorted-set">
      <ServerCapabilitiesType>PRIMARY_TRUST_CENTER</ServerCapabilitiesType>
      <ServerCapabilitiesType>NETWORK_MANAGER</ServerCapabilitiesType>
    </serverCapabilities>
    <incomingTransferSize>128</incomingTransferSize>
    <outgoingTransferSize>128</outgoingTransferSize>
    <userDescriptorAvailable>false</userDescriptorAvailable>
    <frequencyBands>
      <FrequencyBandType>FREQ_2400_MHZ</FrequencyBandType>
    </frequencyBands>
    <macCapabilities class="sorted-set">
      <MacCapabilitiesType>ALTERNATIVE_PAN</MacCapabilitiesType>
      <MacCapabilitiesType>FULL_FUNCTION_DEVICE</MacCapabilitiesType>
      <MacCapabilitiesType>MAINS_POWER</MacCapabilitiesType>
      <MacCapabilitiesType>RECEIVER_ON_WHEN_IDLE</MacCapabilitiesType>
    </macCapabilities>
    <extendedEndpointListAvailable>false</extendedEndpointListAvailable>
    <extendedSimpleDescriptorListAvailable>false</extendedSimpleDescriptorListAvailable>
    <stackCompliance>22</stackCompliance>
  </nodeDescriptor>
  <powerDescriptor>
    <currentPowerMode>RECEIVER_ON_IDLE</currentPowerMode>
    <availablePowerSources>
      <PowerSourceType>MAINS</PowerSourceType>
    </availablePowerSources>
    <currentPowerSource>MAINS</currentPowerSource>
    <powerLevel>FULL</powerLevel>
  </powerDescriptor>
  <endpoints/>
  <bindingTable/>
</ZigBeeNode>

Or is it a access problem to the file? In any case, the content of the file does not change if I deactivate the dongle and want to activate it again.

9

Hi,
Well the update from me is yes I can recreate all of the issues you are seeing.
And I also can rename that xml file and or delete that xml file and see the controller come back online.
does not look like a permissions issue since I granted the everyone account full access and it did not change the behavior. I do not think it is a permissions issue with that file actually. I also tried running the service as a different user and saw same behavior.
I am going to try and go back a to few older versions now and see if this occurs but right now it seems to be only windows 11 specific.
UPDATE:
So this issue is occuing only for versions of OpenHAB 4.04 and higher .
I was able to install a version of openhab 3.4 with zulu 11 and not repo the issues.
Second UPDATE: This appears to be related to Java 17.
even with openhab 3.4 and java 17 the issue immediately reoccurs. (Although java 17 was not the official version for 3.4 versions 3.4 would run on it and mostly function.

10

So the test case to repo this issue is.
Windows 11 patched current as of this posting.
any version of Openhab 3.4 and up to 4.1.1 stable running on ZULU 17
ZigBee ember dongle (Sonoff zigbee 3.0 USB Dongle Plus -E used with firmware version 6.10.3.0 )
This is the dongle list on the vendor site as specifically OpenHAB compatible and has the OpenHAB logo embedded.
Note this may be occurring with other Zigbee controllers but not tested as I do not have any other Zigbee dongles laying around.
Openhab running as a service.
The dongle will stay in initializing until you disable the thing delete or rename the xml file located in the subfolder of the ZigBee folder that matches the dongle.
reenable the dongle thing it then comes online and remains online until you either stop the service and restart it or reboot the box.
Then the dongle remains in the initializing state until you remove the xml file.
Note this issue does not occur if you run the Openhab manually not as a service.
Not sure who can look further into this issue
Unfortunately I have taken it as far as I can with the time I have available.
relevant debug log

2024-01-19 21:33:38.376 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2024-01-19 21:33:38.376 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : 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
2024-01-19 21:33:38.376 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Dependency Manager created ZigBeeChannelConverterFactoryinterface=org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory, filter=null, policy=static, cardinality=1..1, bind=setZigBeeChannelConverterFactory, unbind=unsetZigBeeChannelConverterFactory, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-01-19 21:33:38.376 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : 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
2024-01-19 21:33:38.376 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.zigbee.ember]
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Querying state disabled
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Querying state disabled
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Component can not be activated since it is in state disabled
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory] : Querying state disabled
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Updating target filters
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : No change in target property for dependency $000: currently registered: false
2024-01-19 21:33:38.377 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] :  No existing service listener to unregister for dependency $000
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Setting target property for dependency $000 to null
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : 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)
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm $000 tracker reset (closed)
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm $000 tracker opened
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : registering service listener for dependency $000
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : No change in target property for dependency ZigBeeChannelConverterFactory: currently registered: false
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] :  No existing service listener to unregister for dependency ZigBeeChannelConverterFactory
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Setting target property for dependency ZigBeeChannelConverterFactory to null
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : New service tracker for ZigBeeChannelConverterFactory, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory), initialReferenceFilter (objectClass=org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory)
2024-01-19 21:33:38.378 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm ZigBeeChannelConverterFactory tracker reset (closed)
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm ZigBeeChannelConverterFactory tracking 1 SingleStatic added {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory}={service.id=565, service.bundleid=301, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl, component.id=368} (enter)
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm ZigBeeChannelConverterFactory tracking 1 SingleStatic active: false trackerOpened: false optional: false
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm ZigBeeChannelConverterFactory tracking 1 SingleStatic added {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory}={service.id=565, service.bundleid=301, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl, component.id=368} (exit)
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm ZigBeeChannelConverterFactory tracker opened
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : registering service listener for dependency ZigBeeChannelConverterFactory
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : 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))
2024-01-19 21:33:38.379 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : 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)
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : 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)
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm osgi.ds.satisfying.condition tracker opened
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Changed state from disabled to unsatisfiedReference
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Component enabled
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : ActivateInternal
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Activating component from state unsatisfiedReference
2024-01-19 21:33:38.380 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.381 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.381 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Dependency not satisfied: $000
2024-01-19 21:33:38.381 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Not all dependencies satisfied, cannot activate
2024-01-19 21:33:38.429 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@37c49275[provider=null,keyStore=null,trustStore=null]
2024-01-19 21:33:38.429 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@37c49275[provider=null,keyStore=null,trustStore=null]
2024-01-19 21:33:38.807 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm $000 tracking 3 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=581, service.bundleid=315, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=385} (enter)
2024-01-19 21:33:38.807 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : ActivateInternal
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Activating component from state unsatisfiedReference
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state unsatisfiedReference
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Changed state from unsatisfiedReference to satisfied
2024-01-19 21:33:38.808 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : registration change queue [registered]
2024-01-19 21:33:38.809 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Checking constructor public org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(org.openhab.core.io.transport.serial.SerialPortManager)
2024-01-19 21:33:38.809 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getClassFromComponentClassLoader: Found class org.openhab.core.io.transport.serial.SerialPortManager
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Found constructor with 1 arguments : public org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(org.openhab.core.io.transport.serial.SerialPortManager)
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getting bind: setZigBeeChannelConverterFactory
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Locating method setZigBeeChannelConverterFactory in class org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : doFindMethod: Looking for method org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.setZigBeeChannelConverterFactory
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Declared Method org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.setZigBeeChannelConverterFactory([interface org.osgi.framework.ServiceReference]) not found
2024-01-19 21:33:38.810 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Declared Method org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.setZigBeeChannelConverterFactory([interface org.osgi.service.component.ComponentServiceObjects]) not found
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory through loader of org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getClassFromComponentClassLoader: Found class org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : doFindMethod: Found Method protected void org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.setZigBeeChannelConverterFactory(org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory)
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Found bind method: protected void org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.setZigBeeChannelConverterFactory(org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory)
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : This thread collected dependencies
2024-01-19 21:33:38.811 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getService (single component manager) dependencies collected.
2024-01-19 21:33:38.812 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state satisfied
2024-01-19 21:33:38.812 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Querying state satisfied
2024-01-19 21:33:38.812 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=581, service.bundleid=315, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=385}] service: [null]]]
2024-01-19 21:33:38.812 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : For dependency ZigBeeChannelConverterFactory, optional: false; to bind: [[RefPair: ref: [{org.openhab.binding.zigbee.converter.ZigBeeChannelConverterFactory}={service.id=565, service.bundleid=301, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl, component.id=368}] service: [org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl@28b7e48a]]]
2024-01-19 21:33:38.812 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : 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]]]
2024-01-19 21:33:38.814 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : invoking bind: setZigBeeChannelConverterFactory: parameters [org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl]
2024-01-19 21:33:38.814 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : invoked bind: setZigBeeChannelConverterFactory
2024-01-19 21:33:38.814 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : getting activate: activate
2024-01-19 21:33:38.814 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Locating method activate in class org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory
2024-01-19 21:33:38.814 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Declared Method org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : invoked activate: activate
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Set implementation object for component
2024-01-19 21:33:38.815 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : Changed state from satisfied to active
2024-01-19 21:33:38.816 [DEBUG] [e.ember.internal.EmberHandlerFactory] - bundle org.openhab.binding.zigbee.ember:4.1.1 (306)[org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory(376)] : dm $000 tracking 3 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=581, service.bundleid=315, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=385} (exit)
2024-01-19 21:33:41.173 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-01-19 21:33:41.188 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM3' PAN:c4a2, EPAN:8CFD0348BA1B1B48, Channel:22
2024-01-19 21:33:41.188 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2024-01-19 21:33:42.215 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.ExceptionInInitializerError: null
	at com.thoughtworks.xstream.converters.collections.TreeSetConverter.unmarshal(TreeSetConverter.java:62) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convert(TreeUnmarshaller.java:74) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractReferenceUnmarshaller.convert(AbstractReferenceUnmarshaller.java:72) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:68) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshallField(AbstractReflectionConverter.java:499) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.doUnmarshal(AbstractReflectionConverter.java:425) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshal(AbstractReflectionConverter.java:277) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convert(TreeUnmarshaller.java:74) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractReferenceUnmarshaller.convert(AbstractReferenceUnmarshaller.java:72) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:68) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshallField(AbstractReflectionConverter.java:499) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.doUnmarshal(AbstractReflectionConverter.java:425) ~[?:?]
	at com.thoughtworks.xstream.converters.reflection.AbstractReflectionConverter.unmarshal(AbstractReflectionConverter.java:277) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convert(TreeUnmarshaller.java:74) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractReferenceUnmarshaller.convert(AbstractReferenceUnmarshaller.java:72) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:68) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.convertAnother(TreeUnmarshaller.java:52) ~[?:?]
	at com.thoughtworks.xstream.core.TreeUnmarshaller.start(TreeUnmarshaller.java:136) ~[?:?]
	at com.thoughtworks.xstream.core.AbstractTreeMarshallingStrategy.unmarshal(AbstractTreeMarshallingStrategy.java:32) ~[?:?]
	at com.thoughtworks.xstream.XStream.unmarshal(XStream.java:1464) ~[?:?]
	at com.thoughtworks.xstream.XStream.unmarshal(XStream.java:1441) ~[?:?]
	at com.thoughtworks.xstream.XStream.fromXML(XStream.java:1321) ~[?:?]
	at org.openhab.binding.zigbee.internal.ZigBeeDataStore.readNode(ZigBeeDataStore.java:134) ~[?:?]
	at com.zsmartsystems.zigbee.database.ZigBeeNetworkDatabaseManager.startup(ZigBeeNetworkDatabaseManager.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.initialize(ZigBeeNetworkManager.java:416) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:431) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:557) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.lang.reflect.InaccessibleObjectException: Unable to make field private transient java.util.NavigableMap java.util.TreeSet.m accessible: module java.base does not "opens java.util" to unnamed module @2bfc3ddc
	at java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:354) ~[?:?]
	at java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:297) ~[?:?]
	at java.lang.reflect.Field.checkCanSetAccessible(Field.java:178) ~[?:?]
	at java.lang.reflect.Field.setAccessible(Field.java:172) ~[?:?]
	at com.thoughtworks.xstream.core.util.Fields.locate(Fields.java:40) ~[?:?]
	at com.thoughtworks.xstream.converters.collections.TreeSetConverter$Reflections.<clinit>(TreeSetConverter.java:135) ~[?:?]
	... 33 more
11

As we have an issue that can be reproduced perhaps filing an issue on github would be a good idea so the developer notice. I do not have a windoze machine to test/verify. Since Justin already spent some of his time diagnosing and confirming your issue perhaps @alex200580 could file the issue. Here is link to repository

If this is your first time filing an issue, this will help

12

Hi Alex,
I did this and it works if you want to try it.
open your openHAB-wrapper.conf file and append the following at the bottom of the Wrapper Properties section right below the line

``
set.default.PATH=%PATH%;%KARAF_BASE%\lib;%KARAF_HOME%\lib

add

set.default.PATH=%PATH_WITH_JAVA%
set.default.JDK_JAVA_OPTIONS=--add-reads=java.xml=java.logging --add-exports=java.base/org.apache.karaf.specs.locator=java.xml,ALL-UNNAMED --patch-module java.base=%KARAF_HOME%/lib/endorsed/org.apache.karaf.specs.locator-%KARAF_VERSION%.jar --patch-module java.xml=%KARAF_HOME%/lib/endorsed/org.apache.karaf.specs.java.xml-%KARAF_VERSION%.jar --add-opens java.base/java.security=ALL-UNNAMED --add-opens java.base/java.net=ALL-UNNAMED --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.naming/javax.naming.spi=ALL-UNNAMED --add-opens java.rmi/sun.rmi.transport.tcp=ALL-UNNAMED --add-exports=java.base/sun.net.www.protocol.file=ALL-UNNAMED --add-exports=java.base/sun.net.www.protocol.ftp=ALL-UNNAMED --add-exports=java.base/sun.net.www.protocol.http=ALL-UNNAMED --add-exports=java.base/sun.net.www.protocol.https=ALL-UNNAMED --add-exports=java.base/sun.net.www.protocol.jar=ALL-UNNAMED --add-exports=java.base/sun.net.www.content.text=ALL-UNNAMED --add-exports=jdk.xml.dom/org.w3c.dom.html=ALL-UNNAMED --add-exports=jdk.naming.rmi/com.sun.jndi.url.rmi=ALL-UNNAMED --add-exports=java.rmi/sun.rmi.registry=ALL-UNNAMED --add-exports=java.naming/com.sun.jndi.ldap=ALL-UNNAMED

this resolves the issues.

13

Hello,

thank you very much for the great work. With the addition in the openhab-wrapper.config it works, the dongle goes online. :+1:

It’s unbelievable how quickly and effectively I was helped here! :clap:

Yesterday I created a rule that deletes the XML files every time the dongle leaves the online status, but this is no longer necessary.

Should I still report the issue on Github? Since the problem is reproducible, it will probably affect some others in the future.

14

can not hurt to log a issue at least they can review the additions perhaps trim any that may not really be needed or at least update the documentation after reviewing it.
Glad we got you going. :slightly_smiling_face: