search

openhab / org.openhab.binding.zigbee

openHAB binding for ZigBee
Eclipse Public License 2.0
73 stars 112 forks source link

Only One Ember Coordinator Works at a Time #847

Open madhacker3kxl opened 4 months ago

madhacker3kxl commented 4 months ago

Only one Zigbee coordinator works at a time. Whichever initializes first, it will be the one that will show Online and the other will be communication error. Disabling the working one does not fix the non working one, until restarting services. Example picture where SLZB06 initialized later:

image

Configuration

Configuration Description
Coordinator used SLZB06M and Nortec HUBz
openHAB version 4.2
Hardware LXC
Memory 4GB
Java version Zulu17.50+19-CA
Devices Various sensors and outlets

Logs

Following log is both Zigbee coordinator is disabled and enabling one at at time and disable again. This was after a clean boot. SLZB06M initialzed first.

2024-07-08 10:01:33.861 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-07-08 10:01:33.861 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'rfc2217://192.168.1.208:2001' PAN:2012, EPAN:988499449112ABD6, Channel:25
2024-07-08 10:01:33.861 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (225 * 2^5) = 7200 seconds
2024-07-08 10:01:33.861 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-07-08 10:01:34.862 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-07-08 10:01:34.862 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-07-08 10:01:34.865 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-07-08 10:01:34.869 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee reading network state complete.
2024-07-08 10:01:34.878 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee reading network state complete.
2024-07-08 10:01:34.887 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee reading network state complete.
2024-07-08 10:01:34.894 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee reading network state complete.
2024-07-08 10:01:34.904 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee reading network state complete.
2024-07-08 10:01:34.916 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee reading network state complete.
2024-07-08 10:01:34.923 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee reading network state complete.
2024-07-08 10:01:34.931 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee reading network state complete.
2024-07-08 10:01:34.932 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [rfc2217://192.168.1.208:2001] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2024-07-08 10:01:35.033 [ERROR] [nding.zigbee.serial.ZigBeeSerialPort] - Serial Error: Device cannot be opened on [rfc2217://192.168.1.208:2001]. Caused by Unable to establish remote connection to serial port rfc2217://192.168.1.208:2001, call stack: java.lang.IllegalStateException: Unable to establish remote connection to serial port rfc2217://192.168.1.208:2001
        at org.openhab.core.io.transport.serial.rxtx.rfc2217.internal.SerialPortIdentifierImpl.open(SerialPortIdentifierImpl.java:60)
        at org.openhab.binding.zigbee.serial.ZigBeeSerialPort.open(ZigBeeSerialPort.java:157)
        at org.openhab.binding.zigbee.serial.ZigBeeSerialPort.open(ZigBeeSerialPort.java:123)
        at com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp.initialiseEzspProtocol(ZigBeeDongleEzsp.java:1289)
        at com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp.initialize(ZigBeeDongleEzsp.java:432)
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.initialize(ZigBeeNetworkManager.java:418)
        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.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
        at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: java.net.SocketTimeoutException: Connect timed out
        at java.base/sun.nio.ch.NioSocketImpl.timedFinishConnect(NioSocketImpl.java:551)
        at java.base/sun.nio.ch.NioSocketImpl.connect(NioSocketImpl.java:602)
        at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:327)
        at java.base/java.net.Socket.connect(Socket.java:639)
        at org.apache.commons.net.SocketClient._connect(SocketClient.java:132)
        at org.apache.commons.net.SocketClient.connect(SocketClient.java:268)
        at org.openhab.core.io.transport.serial.rxtx.rfc2217.internal.SerialPortIdentifierImpl.open(SerialPortIdentifierImpl.java:57)
        ... 13 more

2024-07-08 10:01:35.034 [ERROR] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Unable to open serial port
2024-07-08 10:01:35.034 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-07-08 10:01:35.036 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.036 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:01:35.036 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.037 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:01:35.039 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:01:35.041 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.041 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:01:35.041 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.041 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:01:35.042 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:01:35.042 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.042 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:01:35.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.044 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:01:35.042 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.044 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:01:35.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:01:35.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:01:38.754 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_dat is linked although the handler was already disposed.
2024-07-08 10:01:38.755 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_dat is linked although the handler was already disposed.
2024-07-08 10:01:38.755 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_ack is linked although the handler was already disposed.
2024-07-08 10:01:38.755 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_ack is linked although the handler was already disposed.
2024-07-08 10:01:38.756 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_nak is linked although the handler was already disposed.
2024-07-08 10:01:38.756 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_nak is linked although the handler was already disposed.
2024-07-08 10:01:38.757 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 request: http://192.168.7.4/ha_sensors
2024-07-08 10:01:38.790 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 response: {"Sensors":{"esp32_temp":"57.22","zb_temp":"53.87","uptime":5522,"socket_uptime":144,"ram_usage":95,"fs_used":244,"ethernet":true,"wifi_connected":false,"wifi_status":255,"disable_leds":false,"night_mode":false,"auto_zigbee":false}}
2024-07-08 10:01:38.790 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel esp32_temp is linked although the handler was already disposed.
2024-07-08 10:01:38.791 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel zb_temp is linked although the handler was already disposed.
2024-07-08 10:01:38.793 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel uptime is linked although the handler was already disposed.
2024-07-08 10:01:38.793 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel socket_uptime is linked although the handler was already disposed.
2024-07-08 10:01:40.039 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2024-07-08 10:01:40.040 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-07-08 10:01:40.048 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee saving network state complete.
2024-07-08 10:01:40.054 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee saving network state complete.
2024-07-08 10:01:40.060 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee saving network state complete.
2024-07-08 10:01:40.067 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee saving network state complete.
2024-07-08 10:01:40.070 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee saving network state complete.
2024-07-08 10:01:40.080 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee saving network state complete.
2024-07-08 10:01:40.082 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee saving network state complete.
2024-07-08 10:01:40.091 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee saving network state complete.
2024-07-08 10:01:40.092 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-07-08 10:01:40.092 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'rfc2217://192.168.1.208:2001' PAN:2012, EPAN:988499449112ABD6, Channel:25
2024-07-08 10:01:40.095 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (225 * 2^5) = 7200 seconds
2024-07-08 10:01:40.095 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-07-08 10:01:41.096 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-07-08 10:01:41.096 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-07-08 10:01:41.097 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-07-08 10:01:41.106 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee reading network state complete.
2024-07-08 10:01:41.114 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee reading network state complete.
2024-07-08 10:01:41.124 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee reading network state complete.
2024-07-08 10:01:41.131 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee reading network state complete.
2024-07-08 10:01:41.137 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee reading network state complete.
2024-07-08 10:01:41.148 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee reading network state complete.
2024-07-08 10:01:41.150 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee reading network state complete.
2024-07-08 10:01:41.158 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee reading network state complete.
2024-07-08 10:01:41.162 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [rfc2217://192.168.1.208:2001] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2024-07-08 10:01:41.163 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Enabling receive timeout is unsupported on [rfc2217://192.168.1.208:2001]
2024-07-08 10:01:41.163 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [rfc2217://192.168.1.208:2001] is initialized.
2024-07-08 10:01:51.164 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-07-08 10:01:51.169 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.170 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:01:51.170 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:01:51.173 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.175 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:01:51.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.175 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:01:51.175 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.177 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:01:51.174 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.177 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:01:51.177 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.178 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:01:51.173 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:01:51.178 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:01:51.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:01:51.176 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.180 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:01:51.176 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:01:51.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.184 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:01:51.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:01:51.185 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:01:56.169 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2024-07-08 10:01:56.169 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-07-08 10:01:56.177 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee saving network state complete.
2024-07-08 10:01:56.183 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee saving network state complete.
2024-07-08 10:01:56.190 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee saving network state complete.
2024-07-08 10:01:56.195 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee saving network state complete.
2024-07-08 10:01:56.203 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee saving network state complete.
2024-07-08 10:01:56.212 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee saving network state complete.
2024-07-08 10:01:56.216 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee saving network state complete.
2024-07-08 10:01:56.226 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee saving network state complete.
2024-07-08 10:01:56.229 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port 'rfc2217://192.168.1.208:2001' closed.
2024-07-08 10:01:56.230 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-07-08 10:01:56.230 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'rfc2217://192.168.1.208:2001' PAN:2012, EPAN:988499449112ABD6, Channel:25
2024-07-08 10:01:56.230 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (225 * 2^5) = 7200 seconds
2024-07-08 10:01:56.231 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-07-08 10:01:57.231 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-07-08 10:01:57.231 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-07-08 10:01:57.232 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-07-08 10:01:57.240 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee reading network state complete.
2024-07-08 10:01:57.244 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee reading network state complete.
2024-07-08 10:01:57.250 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee reading network state complete.
2024-07-08 10:01:57.257 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee reading network state complete.
2024-07-08 10:01:57.265 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee reading network state complete.
2024-07-08 10:01:57.272 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee reading network state complete.
2024-07-08 10:01:57.277 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee reading network state complete.
2024-07-08 10:01:57.283 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee reading network state complete.
2024-07-08 10:01:57.287 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [rfc2217://192.168.1.208:2001] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2024-07-08 10:01:57.290 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Enabling receive timeout is unsupported on [rfc2217://192.168.1.208:2001]
2024-07-08 10:01:57.293 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [rfc2217://192.168.1.208:2001] is initialized.
2024-07-08 10:02:07.295 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-07-08 10:02:07.297 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.298 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:02:07.298 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.300 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:02:07.300 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:02:07.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:02:07.301 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:02:07.298 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.301 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:02:07.302 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.305 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Coordinator is unknown or not online.
2024-07-08 10:02:07.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Coordinator is unknown or not online.
2024-07-08 10:02:07.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Coordinator is unknown or not online.
2024-07-08 10:02:07.301 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.307 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Coordinator is unknown or not online.
2024-07-08 10:02:07.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.307 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Coordinator is unknown or not online.
2024-07-08 10:02:07.302 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.308 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Coordinator is unknown or not online.
2024-07-08 10:02:07.302 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator status changed to OFFLINE.
2024-07-08 10:02:07.308 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Coordinator is unknown or not online.
2024-07-08 10:02:08.796 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_dat is linked although the handler was already disposed.
2024-07-08 10:02:08.796 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_dat is linked although the handler was already disposed.
2024-07-08 10:02:08.797 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_ack is linked although the handler was already disposed.
2024-07-08 10:02:08.797 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_ack is linked although the handler was already disposed.
2024-07-08 10:02:08.797 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_nak is linked although the handler was already disposed.
2024-07-08 10:02:08.797 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_nak is linked although the handler was already disposed.
2024-07-08 10:02:08.797 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 request: http://192.168.7.4/ha_sensors
2024-07-08 10:02:08.832 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 response: {"Sensors":{"esp32_temp":"57.22","zb_temp":"53.45","uptime":5552,"socket_uptime":174,"ram_usage":96,"fs_used":244,"ethernet":true,"wifi_connected":false,"wifi_status":255,"disable_leds":false,"night_mode":false,"auto_zigbee":false}}
2024-07-08 10:02:08.832 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel esp32_temp is linked although the handler was already disposed.
2024-07-08 10:02:08.833 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel zb_temp is linked although the handler was already disposed.
2024-07-08 10:02:08.834 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel uptime is linked although the handler was already disposed.
2024-07-08 10:02:08.834 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel socket_uptime is linked although the handler was already disposed.
2024-07-08 10:02:10.929 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F511: Handler dispose.
2024-07-08 10:02:10.929 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:00124b002228f511
2024-07-08 10:02:10.936 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002228F3EE: Handler dispose.
2024-07-08 10:02:10.936 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:00124b002228f3ee
2024-07-08 10:02:10.943 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B0022417A66: Handler dispose.
2024-07-08 10:02:10.943 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:00124b0022417a66
2024-07-08 10:02:10.947 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001F918E75: Handler dispose.
2024-07-08 10:02:10.949 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:00124b001f918e75
2024-07-08 10:02:10.954 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B800000736F7: Handler dispose.
2024-07-08 10:02:10.955 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:f0d1b800000736f7
2024-07-08 10:02:10.963 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000121FED: Handler dispose.
2024-07-08 10:02:10.963 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:f0d1b80000121fed
2024-07-08 10:02:10.967 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001C4B2B98: Handler dispose.
2024-07-08 10:02:10.967 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker removed for thingUID=zigbee:device:9ae54a24:00124b001c4b2b98
2024-07-08 10:02:10.977 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-07-08 10:02:10.985 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B0022417A66: ZigBee saving network state complete.
2024-07-08 10:02:10.992 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F3EE: ZigBee saving network state complete.
2024-07-08 10:02:10.998 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001C4B2B98: ZigBee saving network state complete.
2024-07-08 10:02:11.002 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001F918E75: ZigBee saving network state complete.
2024-07-08 10:02:11.011 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B002228F511: ZigBee saving network state complete.
2024-07-08 10:02:11.018 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B800000736F7: ZigBee saving network state complete.
2024-07-08 10:02:11.021 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F001200B3E3: ZigBee saving network state complete.
2024-07-08 10:02:11.026 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - F0D1B80000121FED: ZigBee saving network state complete.
2024-07-08 10:02:11.032 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port 'rfc2217://192.168.1.208:2001' closed.
2024-07-08 10:02:11.032 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_ember:9ae54a24] closed.
2024-07-08 10:02:11.034 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 9 MultipleDynamic removed {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=697, service.bundleid=300, service.scope=singleton} (enter)
2024-07-08 10:02:11.034 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoking unbind: removeZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2024-07-08 10:02:11.034 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoked unbind: removeZigBeeCoordinatorHandler
2024-07-08 10:02:11.036 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 9 MultipleDynamic removed (unbind) {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=697, service.bundleid=300, service.scope=singleton}
2024-07-08 10:02:16.924 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=720, service.bundleid=303, service.scope=singleton} (enter)
2024-07-08 10:02:16.924 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic already active, binding {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=720, service.bundleid=303, service.scope=singleton}
2024-07-08 10:02:16.924 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoking bind: addZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.slzb06.handler.Slzb06Handler]
2024-07-08 10:02:16.925 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoked bind: addZigBeeCoordinatorHandler
2024-07-08 10:02:16.925 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 10 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=720, service.bundleid=303, service.scope=singleton} (exit)
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_slzb06:56a865c77e].
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 26
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 34009
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 26D2F2BE80D4CD5C
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 3C84975B13EFDB684E490F5CF0F977F3
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 2A6967426565716C6C69616E63653039
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
2024-07-08 10:02:16.927 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 3C84975B13EFDB684E490F5CF0F977F3
2024-07-08 10:02:16.928 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 3C84975B13EFDB684E490F5CF0F977F3
2024-07-08 10:02:16.928 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 2A6967426565716C6C69616E63653039
2024-07-08 10:02:16.928 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 2A6967426565716C6C69616E63653039
2024-07-08 10:02:16.928 [DEBUG] [.zigbee.slzb06.handler.Slzb06Handler] - Initializing ZigBee SLZB06 network bridge handler.
2024-07-08 10:02:16.928 [DEBUG] [.zigbee.slzb06.handler.Slzb06Handler] - ZigBee SLZB06 Coordinator opening Port:'192.168.7.4:2003' PAN:84d9, EPAN:26D2F2BE80D4CD5C, Channel:26
2024-07-08 10:02:16.928 [DEBUG] [.zigbee.slzb06.handler.Slzb06Handler] - ZigBee SLZB06 end device poll timeout set to (169 * 2^8) = 43264 seconds
2024-07-08 10:02:16.933 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@5f9ea880[provider=null,keyStore=null,trustStore=null]
2024-07-08 10:02:16.933 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@5f9ea880[provider=null,keyStore=null,trustStore=null]
2024-07-08 10:02:16.934 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 communicator created for 192.168.7.4
2024-07-08 10:02:16.934 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-07-08 10:02:17.934 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-07-08 10:02:17.934 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-07-08 10:02:17.937 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-07-08 10:02:17.937 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 943469FFFE73C56E: ZigBee reading network state complete.
2024-07-08 10:02:17.939 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B00222E9346: ZigBee reading network state complete.
2024-07-08 10:02:17.940 [DEBUG] [ee.slzb06.internal.Slzb06NetworkPort] - SLZB06 Connecting to network port [192.168.7.4:2003]
2024-07-08 10:02:17.941 [DEBUG] [ee.slzb06.internal.Slzb06NetworkPort] - SLZB06 '192.168.7.4': Network port is initialized.
2024-07-08 10:02:17.942 [DEBUG] [ee.slzb06.internal.Slzb06NetworkPort] - SLZB06 '192.168.7.4': ReceiveThread started
2024-07-08 10:02:19.026 [DEBUG] [ee.slzb06.internal.Slzb06NetworkPort] - SLZB06 '192.168.7.4': ReceiveThread closed
2024-07-08 10:02:19.026 [DEBUG] [ee.slzb06.internal.Slzb06NetworkPort] - SLZB06 '192.168.7.4': Network port closed.
2024-07-08 10:02:19.027 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_slzb06:56a865c77e] closed.
2024-07-08 10:02:30.594 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 21600
2024-07-08 10:02:30.599 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_00, PanID=0, EPanId=0000000000000000
2024-07-08 10:02:30.599 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_SECURE
2024-07-08 10:02:32.099 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 943469FFFE73C56E: networkStateUpdated called with state=ONLINE
2024-07-08 10:02:32.109 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00124B00222E9346: Starting ZigBee device discovery
2024-07-08 10:02:32.110 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B00222E9346: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_slzb06:56a865c77e
2024-07-08 10:02:32.113 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B00222E9346: Node discovery not complete
2024-07-08 10:02:32.349 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 943469FFFE73C56E: ZigBee saving network state complete.
2024-07-08 10:02:32.353 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B00222E9346: ZigBee saving network state complete.
2024-07-08 10:02:32.383 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee initialise done. channel=CHANNEL_26, PanId=34009  EPanId=26D2F2BE80D4CD5C
2024-07-08 10:02:32.880 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 943469FFFE73C56E: ZigBee saving network state complete.
2024-07-08 10:02:52.303 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 943469FFFE73C56E: networkStateUpdated called with state=OFFLINE
2024-07-08 10:02:52.303 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - UNINITIALIZED: Ignoring OFFLINE status: Thing is {}
2024-07-08 10:02:52.303 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 943469FFFE73C56E: networkStateUpdated called with state=SHUTDOWN
2024-07-08 10:02:52.307 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 943469FFFE73C56E: ZigBee saving network state complete.
2024-07-08 10:02:52.308 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B00222E9346: ZigBee saving network state complete.
2024-07-08 10:02:57.306 [WARN ] [core.thing.internal.ThingManagerImpl] - Disposing handler for thing 'zigbee:coordinator_slzb06:56a865c77e' takes more than 5000ms.
2024-07-08 10:02:57.309 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 11 MultipleDynamic removed {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=720, service.bundleid=303, service.scope=singleton} (enter)
2024-07-08 10:02:57.312 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoking unbind: removeZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.slzb06.handler.Slzb06Handler]
2024-07-08 10:02:57.312 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : invoked unbind: removeZigBeeCoordinatorHandler
2024-07-08 10:02:57.312 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.2.0 (295)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(344)] : dm ZigBeeCoordinatorHandler tracking 11 MultipleDynamic removed (unbind) {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=720, service.bundleid=303, service.scope=singleton}
2024-07-08 10:03:02.384 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_dat is linked although the handler was already disposed.
2024-07-08 10:03:02.384 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_dat is linked although the handler was already disposed.
2024-07-08 10:03:02.384 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_ack is linked although the handler was already disposed.
2024-07-08 10:03:02.384 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_ack is linked although the handler was already disposed.
2024-07-08 10:03:02.385 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel rx_nak is linked although the handler was already disposed.
2024-07-08 10:03:02.385 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel tx_nak is linked although the handler was already disposed.
2024-07-08 10:03:02.385 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 request: http://192.168.7.4/ha_sensors
2024-07-08 10:03:02.419 [DEBUG] [zb06.internal.api.Slzb06Communicator] - SLZB06 response: {"Sensors":{"esp32_temp":"56.67","zb_temp":"54.65","uptime":5606,"socket_uptime":228,"ram_usage":95,"fs_used":244,"ethernet":true,"wifi_connected":false,"wifi_status":255,"disable_leds":false,"night_mode":false,"auto_zigbee":false}}
2024-07-08 10:03:02.420 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel esp32_temp is linked although the handler was already disposed.
2024-07-08 10:03:02.420 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel zb_temp is linked although the handler was already disposed.
2024-07-08 10:03:02.420 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel uptime is linked although the handler was already disposed.
2024-07-08 10:03:02.422 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler Slzb06Handler of thing zigbee:coordinator_slzb06:56a865c77e tried checking if channel socket_uptime is linked although the handler was already disposed.
cdjackson commented 4 months ago

Unfortunately the logging doesn't show the info from the zigbee library, but I suspect that the issue here is that both of these are Ember chips and they are running very different firmware. There is a constraint in the zigbee library where a static is used to store information for communication with the dongle -:

https://github.com/zsmartsystems/com.zsmartsystems.zigbee/issues/1149

I couldn't find info on the HUBz - is that the HUSBZB1 or is it different? If you can find newer firmware, then it would work I think. I'll also take a look at the library issue again, but that's also messy to resolve.

madhacker3kxl commented 4 months ago

It is the HUSBZB1. Yes, they are both ember devices. There is no newer firmware version for it. I am not worried about it, as I needed them both online to move devices from one to another, one at a time. You are welcome to close this issue. If you need more logs, let me know.

cdjackson commented 4 months ago

Thanks.

I'll modify the title to reflect that this is Ember and leave it open for now. I'll take another look to see if this can be resolved in an elegant way in the library as it isn't a nice "feature" to have. Interestingly though, in all the years this is the first time it's come up in OH at least.