Can't get Elelabs zigbee stick to come online after system shutdown

I’ve been using one of the Elelabs USB sticks with great success for a while, but today I had to shut down my system to move it and now I can’t seem to get the ember coordinator thing to come back online. It just stays at “unknown”. I’ve tried deleting the coordinator and re-adding it, shutting down openhab and unplugging the physical stick and plugging it back in, and even trying the snapshot version of the binding. I’m currently running 2.4 M5 on a box running Ubuntu. This is the only log I get, even with debug for org.openhab.binding.zigbee and com.zsmartsystems.zigbee turned on - it just seems to hang after “Serial port [/dev/ttyUSB-Zigbee] is initalized” (ttyUSB-Zigbee is a udev symlink , and I’ve checked lsusb and dmesg to see that it is being recognized by the system). Any thoughts? I’m not sure where to go from here unless I need to delete and re-add the three bulbs I have paired and start over.

09-Nov-2018 17:53:05.373 [DEBUG] [org.openhab.binding.zigbee.xbee                   ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
09-Nov-2018 17:53:05.374 [DEBUG] [org.openhab.binding.zigbee.xbee                   ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
09-Nov-2018 17:53:20.722 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:zigbee_stick].
09-Nov-2018 17:53:20.724 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Channel 11
09-Nov-2018 17:53:20.725 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - PANID 5REDACTED9
09-Nov-2018 17:53:20.725 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - EPANID 6REDACTED8
09-Nov-2018 17:53:20.726 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Network Key EREDACTEDC
09-Nov-2018 17:53:20.726 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Link Key 5REDACTED9
09-Nov-2018 17:53:20.726 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Network Key String EREDACTEDC
09-Nov-2018 17:53:20.727 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Network key final array EREDACTEDC
09-Nov-2018 17:53:20.727 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Link Key String 5REDACTED9
09-Nov-2018 17:53:20.727 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Link key final array 5REDACTED9
09-Nov-2018 17:53:20.741 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
09-Nov-2018 17:53:20.745 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=380, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:20.749 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=382, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:20.752 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=383, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:20.773 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Initializing ZigBee thing handler zigbee:device:zigbee_stick:d0cf5efffe2381ab
09-Nov-2018 17:53:20.775 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Coordinator status changed to UNKNOWN.
09-Nov-2018 17:53:20.775 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Coordinator is unknown or not online.
09-Nov-2018 17:53:20.782 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Channel zigbee:device:zigbee_stick:d0cf5efffe2381ab:D0CF5EFFFE2381AB_1_switch linked - polling started.
09-Nov-2018 17:53:20.781 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Channel zigbee:device:zigbee_stick:d0cf5efffe2381ab:D0CF5EFFFE2381AB_1_dimmer linked - polling started.
09-Nov-2018 17:53:20.782 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE2381AB: Channel zigbee:device:zigbee_stick:d0cf5efffe2381ab:D0CF5EFFFE2381AB_1_color linked - polling started.
09-Nov-2018 17:53:21.103 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=389, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.111 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=390, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.117 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=391, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.121 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Initializing ZigBee thing handler zigbee:device:zigbee_stick:000b57fffe9ffe65
09-Nov-2018 17:53:21.123 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Coordinator status changed to UNKNOWN.
09-Nov-2018 17:53:21.126 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Coordinator is unknown or not online.
09-Nov-2018 17:53:21.171 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Channel zigbee:device:zigbee_stick:000b57fffe9ffe65:000B57FFFE9FFE65_1_color linked - polling started.
09-Nov-2018 17:53:21.177 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Channel zigbee:device:zigbee_stick:000b57fffe9ffe65:000B57FFFE9FFE65_1_dimmer linked - polling started.
09-Nov-2018 17:53:21.177 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - 000B57FFFE9FFE65: Channel zigbee:device:zigbee_stick:000b57fffe9ffe65:000B57FFFE9FFE65_1_switch linked - polling started.
09-Nov-2018 17:53:21.302 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=409, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.303 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=410, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.303 [DEBUG] [org.openhab.binding.zigbee                        ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=411, service.bundleid=311, service.scope=singleton} - org.openhab.binding.zigbee
09-Nov-2018 17:53:21.322 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Initializing ZigBee thing handler zigbee:device:zigbee_stick:d0cf5efffe25819e
09-Nov-2018 17:53:21.322 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Coordinator status changed to UNKNOWN.
09-Nov-2018 17:53:21.323 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Coordinator is unknown or not online.
09-Nov-2018 17:53:21.324 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Channel zigbee:device:zigbee_stick:d0cf5efffe25819e:D0CF5EFFFE25819E_1_color linked - polling started.
09-Nov-2018 17:53:21.325 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Channel zigbee:device:zigbee_stick:d0cf5efffe25819e:D0CF5EFFFE25819E_1_switch linked - polling started.
09-Nov-2018 17:53:21.325 [DEBUG] [.openhab.binding.zigbee.handler.ZigBeeThingHandler] - D0CF5EFFFE25819E: Channel zigbee:device:zigbee_stick:d0cf5efffe25819e:D0CF5EFFFE25819E_1_dimmer linked - polling started.
09-Nov-2018 17:53:21.744 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
09-Nov-2018 17:53:21.745 [DEBUG] [ab.binding.zigbee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
09-Nov-2018 17:53:21.936 [DEBUG] [rg.openhab.binding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB-Zigbee] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
09-Nov-2018 17:53:21.946 [DEBUG] [rg.openhab.binding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB-Zigbee] is initialized.

Try using the port that the symlink points to. If that works, then my guess is that you haven’t configured the EXTRA_JAVA_OPTS, but chose a non-standard name for the link. This old wiki entry explains what a standard name would look like (check the note at the bottom). Hopefully this helps!

1 Like

The symlink config was good - it was working before I shut down but for grins I changed it to the source device (ttyUSB0). Still nothing after “Serial port [/dev/ttyUSB0] is initialized” :frowning: . I don’t know if it could somehow be the stick itself (which would be a bummer, i’ve had it less than a month), but there’s a test application on the @Elelabs website, unfortunately it’s 404 so dead end there. I tried turning on debug for com.zsmartsystems.zigbee.dongle.ember and I get this about once a second or two. I’m not sure what that means though. Debug for org.openhab.binding.zigbee.ember doesn’t produce any additional output.

10-Nov-2018 08:18:31.080 [DEBUG] [s.zigbee.dongle.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst []
10-Nov-2018 08:18:32.681 [DEBUG] [s.zigbee.dongle.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst []
10-Nov-2018 08:18:34.281 [DEBUG] [s.zigbee.dongle.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst []

Edit: I’m using their recommended settings of Software (XOn/XOff) flow control and 57600 baud rate as well. All other coordinator thing settings have always been left as default. Also, different physical USB ports

I wasn’t questioning the symlink config, but Java’s ability to access the port. Trying ttyUSB0 eliminates one possible cause. Next I’d look at permissions (basically starting from the beginning of the setup steps). I’d also look at the hardware. If lsusb and dmesg don’t reveal any issues, check in the system log. Then look at permissions on /dev/ttyUSB0 (check the docs for how they should be setup).

Maybe @Elelabs could help troubleshoot the device (or their website)?

If you already have org.openhab.binding.zigbee in debug, then this is expected, as all child namespaces will inherit the logging from the parent. Have you tried enabling debug on com.zsmartsystems.zigbee? Do you see any other errors on OH startup?

Hopefully with enough troubleshooting, something will pop out. I’ve seen others report similar issues and then after the 10th restart, everything worked again! :slightly_smiling_face:

This is the driver sending reset commands to the dongle. It’s the first thing that is done when the binding starts, and as there is no response it keeps trying every second or two.

No worries, I get what you were getting at. Nothing seems out of the ordinary and permissions look good, they match my working ZWave dongle as well

crw-rw-rw- 1 root dialout 166,   0 Nov 10 10:08 ttyACM0
...
crw-rw-rw- 1 root dialout 188,   0 Nov 10 10:07 ttyUSB0
lrwxrwxrwx 1 root root           7 Nov 10 09:47 ttyUSB-Zigbee -> ttyUSB0
lrwxrwxrwx 1 root root           7 Nov 10 09:47 ttyUSB-ZWave -> ttyACM0

I didn’t think that’s how it worked because with both org.openhab.binding.zigbee and com.zsmartsystems.zigbee set to debug I get the log in the initial post and nothing more but if I turn on
com.zsmartsystems.zigbee.dongle.ember I get the additonal AshFrameRst retries

Interesting. Ok. I downloaded and setup a new openhab snapshot build on my main Windows box and when I plugged the stick in and added the coordinator thing there it came right online, so it’s definitely not the stick itself. I also swapped my zwave and zigbee sticks in the USB ports and zwave continued to work so it’s not the physical USB ports. It’s the damnedest thing. This system was working absolutely fine for over a week, all I did was do a shutdown -h now, unplug it, and plug it in over in another room.

This may be tricky to explain/understand. A child will inherit from the parent, unless the child has already been specifically set. My guess is that you had set the logging for com.zsmartsystems.zigbee.dongle.ember, then turned it off, so inheritance did not occur. Make sense?

Run log:get to show current logging levels. You can’t remove any of these entries (setting the level to ‘DEFAULT’ might work… haven’t tried), and they are backed up in /userdata/etc/org.ops4j.pax.logging.cfg, so they will persist OH restarts. To completely remove an entry, remove it from the file (make a backup first!) and then restart OH. If you check log:display again, you will see it is gone.

Sounds like there might have been a setting or software update that took effect after the restart. Does journalctl show anything? At least you know the hardware works!

That actually makes perfect sense, thanks for the explanation

It does sound like there was a setting or update somewhere that waited for the shutdown, doesn’t it? I don’t see anything sticking out in journalctl either, unfortunately.

Edit:
I just watched the openhab.log file while deleting and re-creating the coordinator thing and a few seconds after the log says that the serial port was initialized I see these three errors:

2018-11-10 12:23:04.242 [ERROR] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH error: BAD PACKET 
2018-11-10 12:23:05.842 [ERROR] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH error: BAD PACKET 
2018-11-10 12:23:07.442 [ERROR] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH error: BAD PACKET 

I just wanted to follow up and say that moving the zigbee stick to a powered USB hub and reinstalling the binding seems to have fixed the issue. Why it worked originally for so long until I moved it, I’ll never know but it’s working now so I’ll take it.

1 Like

Dear Zolakk,
Are u using windows with a Zigbee device now or is it a linux setup you are using.

I am looking at the moment for a Zigbee usb dongle under windows that works with openhab. Z-Wave is working fine with a different device but I need the Zigbee also in addition…

My system runs Ubuntu, I’m not sure if the elelabs zigbee stick will work with Windows unfortunately. I couldn’t find any mention of it on their website or docs. It might be better to ask in the main zigbee binding thread what works with Windows

It should be ok - I’ve not tested it myself (I tend to use the CEL stick) but as it is a standard Silabs chipset, it should work fine.

Sorry for reopening this old thread, but as I am facing exactly the same challenge and the question still seems to be unanswered I would like to raise it again: Is anybody using the Elelabs (ELU013) Zigbee stick successfully with Openhab on Windows? I unfortunately cannot get it to work. Within the Windows device manager the device shows as OK:
grafik

But in OH the thing is always toggling between OFFLINE und UNKNOWN:
grafik

Does anybody know a solution for this? Or maybe any other Zigbee stick that works with OH on Windows? Thanks for any help!

Please provide information showing why this is happening - it will help us to understand the issue.

I really would like to help, so what shoukd I do? Setting Zigbee binding to Debug mode and post a log?

Yes, logging will help to understand what’s happening.

I just post the normal log first, as I have not yet switched to Debug mode, maybe it helps. So, this occurs every 10 - 20 seconds:

2020-09-27 13:03:42.437 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 13:03:43.443 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 13:03:54.918 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 13:03:59.918 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 13:04:11.373 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 13:04:16.387 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee

Is this of any help? Otherwise I will switch on DEBUG logging.

It looks like the dongle is not responding, but a debug log would help to confirm that.

ok, so here is the dubug log:

2020-09-27 14:31:30.962 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 14:31:30.962 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2020-09-27 14:31:35.966 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 14:31:35.967 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=OFFLINE
2020-09-27 14:31:35.967 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set to 0ms
2020-09-27 14:31:35.967 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set less than Max Deferred Write Time
2020-09-27 14:31:35.968 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Max Deferred Write Time set to 0ms
2020-09-27 14:31:35.971 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2020-09-27 14:31:35.972 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Shutdown
2020-09-27 14:31:35.972 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2020-09-27 14:31:35.972 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2020-09-27 14:31:35.973 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false, initialised=false, networkStateUp=false
2020-09-27 14:31:35.973 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false ignored.
2020-09-27 14:31:35.983 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port 'COM3' closed.
2020-09-27 14:31:35.983 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Shutdown
2020-09-27 14:31:35.983 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Queue shutdown
2020-09-27 14:31:35.984 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Queue shutdown
2020-09-27 14:31:35.984 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2020-09-27 14:31:35.985 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM3' PAN:6c10, EPAN:2BB618AC0AB214C1, Channel:11
2020-09-27 14:31:35.986 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2020-09-27 14:31:35.986 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2020-09-27 14:31:36.987 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2020-09-27 14:31:36.987 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2020-09-27 14:31:36.988 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=50, maxRetries=2]
2020-09-27 14:31:36.988 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:31:36.988 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:31:36.989 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
2020-09-27 14:31:36.989 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2020-09-27 14:31:36.990 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2020-09-27 14:31:36.990 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2020-09-27 14:31:36.990 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [COM3] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2020-09-27 14:31:37.069 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [COM3] is initialized.
2020-09-27 14:31:40.255 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_KOCH_CU_LCH' does not exist.
2020-09-27 14:31:40.255 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_KOCH_CU_LUP' does not exist.
2020-09-27 14:31:40.258 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_KOCH_CU_LUP' does not exist.
2020-09-27 14:31:40.466 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_SPEI_SS_LUP' does not exist.
2020-09-27 14:31:40.502 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_SPEI_SS_LUP' does not exist.
2020-09-27 14:31:41.329 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_AEIN_S2_LUP' does not exist.
2020-09-27 14:31:47.099 [INFO ] [.reconnect.PeriodicReconnectStrategy] - Try to restore connection to '192.168.178.78'. Next attempt in 60000ms
2020-09-27 14:31:47.100 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.178.78' with clientid 80eed35c-8818-46f4-bae4-25d4f608fa6f
2020-09-27 14:31:47.401 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - No response from ezspVersion command
2020-09-27 14:31:47.402 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Version returned null. ASH/EZSP not initialised.
2020-09-27 14:31:47.402 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2020-09-27 14:31:47.403 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2020-09-27 14:31:47.403 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 14:31:52.405 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 14:31:52.406 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=OFFLINE
2020-09-27 14:31:52.406 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set to 0ms
2020-09-27 14:31:52.406 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set less than Max Deferred Write Time
2020-09-27 14:31:52.407 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Max Deferred Write Time set to 0ms
2020-09-27 14:31:52.411 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2020-09-27 14:31:52.411 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Shutdown
2020-09-27 14:31:52.411 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2020-09-27 14:31:52.412 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2020-09-27 14:31:52.412 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false, initialised=false, networkStateUp=false
2020-09-27 14:31:52.413 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false ignored.
2020-09-27 14:31:52.427 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port 'COM3' closed.
2020-09-27 14:31:52.427 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Shutdown
2020-09-27 14:31:52.428 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Queue shutdown
2020-09-27 14:31:52.428 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Queue shutdown
2020-09-27 14:31:52.429 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2020-09-27 14:31:52.430 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM3' PAN:6c10, EPAN:2BB618AC0AB214C1, Channel:11
2020-09-27 14:31:52.430 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2020-09-27 14:31:52.431 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2020-09-27 14:31:53.432 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2020-09-27 14:31:53.432 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2020-09-27 14:31:53.432 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=50, maxRetries=2]
2020-09-27 14:31:53.432 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:31:53.433 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:31:53.433 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
2020-09-27 14:31:53.433 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2020-09-27 14:31:53.434 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2020-09-27 14:31:53.434 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2020-09-27 14:31:53.434 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [COM3] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2020-09-27 14:31:53.562 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [COM3] is initialized.
2020-09-27 14:31:55.114 [WARN ] [erial.internal.SerialPortManagerImpl] - No SerialPortProvider found for: rfc2217://192.168.176.59:7000
2020-09-27 14:31:55.188 [WARN ] [erial.internal.SerialPortManagerImpl] - No SerialPortProvider found for: rfc2217://192.168.176.67:7000
2020-09-27 14:31:59.387 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_KOCH_CU_LUP' does not exist.
2020-09-27 14:32:03.894 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - No response from ezspVersion command
2020-09-27 14:32:03.894 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Version returned null. ASH/EZSP not initialised.
2020-09-27 14:32:03.895 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2020-09-27 14:32:03.895 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 14:32:03.895 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2020-09-27 14:32:08.898 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 14:32:08.899 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=OFFLINE
2020-09-27 14:32:08.899 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set to 0ms
2020-09-27 14:32:08.900 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set less than Max Deferred Write Time
2020-09-27 14:32:08.900 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Max Deferred Write Time set to 0ms
2020-09-27 14:32:08.904 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2020-09-27 14:32:08.904 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Shutdown
2020-09-27 14:32:08.904 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2020-09-27 14:32:08.905 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2020-09-27 14:32:08.946 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false, initialised=false, networkStateUp=false
2020-09-27 14:32:08.946 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false ignored.
2020-09-27 14:32:08.962 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port 'COM3' closed.
2020-09-27 14:32:08.963 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Shutdown
2020-09-27 14:32:08.963 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Queue shutdown
2020-09-27 14:32:08.963 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Queue shutdown
2020-09-27 14:32:08.964 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2020-09-27 14:32:08.965 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM3' PAN:6c10, EPAN:2BB618AC0AB214C1, Channel:11
2020-09-27 14:32:08.965 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2020-09-27 14:32:08.966 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2020-09-27 14:32:09.967 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2020-09-27 14:32:09.967 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2020-09-27 14:32:09.968 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=50, maxRetries=2]
2020-09-27 14:32:09.968 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:32:09.969 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2020-09-27 14:32:09.969 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
2020-09-27 14:32:09.970 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2020-09-27 14:32:09.971 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2020-09-27 14:32:09.971 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2020-09-27 14:32:09.971 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [COM3] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2020-09-27 14:32:10.065 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [COM3] is initialized.
2020-09-27 14:32:10.259 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_KOCH_CU_LCH' does not exist.
2020-09-27 14:32:10.259 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_KOCH_CU_LUP' does not exist.
2020-09-27 14:32:10.262 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_KOCH_CU_LUP' does not exist.
2020-09-27 14:32:10.464 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'PO_SPEI_SS_LUP' does not exist.
2020-09-27 14:32:10.506 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_SPEI_SS_LUP' does not exist.
2020-09-27 14:32:11.386 [WARN ] [rthome.model.script.actions.BusEvent] - Item 'EN_AEIN_S2_LUP' does not exist.
2020-09-27 14:32:20.401 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - No response from ezspVersion command
2020-09-27 14:32:20.401 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Version returned null. ASH/EZSP not initialised.
2020-09-27 14:32:20.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2020-09-27 14:32:20.402 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_262]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_262]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_262]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_262]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_262]
2020-09-27 14:32:20.402 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2020-09-27 14:32:25.115 [WARN ] [erial.internal.SerialPortManagerImpl] - No SerialPortProvider found for: rfc2217://192.168.176.59:7000
2020-09-27 14:32:25.189 [WARN ] [erial.internal.SerialPortManagerImpl] - No SerialPortProvider found for: rfc2217://192.168.176.67:7000
2020-09-27 14:32:25.406 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2020-09-27 14:32:25.406 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=OFFLINE
2020-09-27 14:32:25.406 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set to 0ms
2020-09-27 14:32:25.406 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set less than Max Deferred Write Time
2020-09-27 14:32:25.406 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Max Deferred Write Time set to 0ms
2020-09-27 14:32:25.408 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2020-09-27 14:32:25.408 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Shutdown
2020-09-27 14:32:25.408 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2020-09-27 14:32:25.408 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2020-09-27 14:32:25.409 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false, initialised=false, networkStateUp=false
2020-09-27 14:32:25.409 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember: Link State change to false ignored.
2020-09-27 14:32:25.420 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port 'COM3' closed.
2020-09-27 14:32:25.422 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Shutdown
2020-09-27 14:32:25.422 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Queue shutdown
2020-09-27 14:32:25.422 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Queue shutdown
2020-09-27 14:32:25.422 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2020-09-27 14:32:25.422 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'COM3' PAN:6c10, EPAN:2BB618AC0AB214C1, Channel:11
2020-09-27 14:32:25.422 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2020-09-27 14:32:25.422 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start

This confirms that the dongle is not responding. Either the configuration is wrong somehow (wrong port, speed etc) or the dongle isn’t working, or there’s something else wrong in that chain.