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.
ok, thank you for the quick analysis. The COM Port is correct. So I will try to tweak with the speeds etc. Do you know if there is any other software which I could use on Windows to see if the stick is somehow responding?
I think the speed is correct for this firmware, but if it’s not, really the only other option that’s likely is 57k6.
I don’t tend to use Windows so can’t really help there. The interface is binary though so things like terminal won’t work as you won’t be able to send a correctly formatted frame to solicit a response.
I played around with all the parameters, but no success…
Well, I just tested it on a Raspi now to make sure that it is not the stick hardware itself. On the raspi the stick works fine with the Ember default parameters (115.2k etc.).
I am really wondering if anybody in this wide world has a Zigbee stick up and running with OH on Windows??
OpenHAB serial ports work just fine with Windows, so I’m sure there is no problem at all there.
I know, I am using a number of bindings which make use of the COM ports (e.g. your Z-Wave binding). It is probably a driver issue. So far I already tried the bitronvideo stick and now the Elelabs stick, both not usable with Windows…
There must be something wrong with your configuration - sorry. I’ve certainly tested this on Windows - I have a headless system that I use for testing with a SmartMeter for some customers, and I use this also for a sniffer - this uses exactly the same software as used in the OH binding and it works fine.
Thinking about this some more, I’d be really very surprised if no-one is using the ZigBee binding under Windows 
. Windows is quite a common OS, and ZigBee is quite a popular binding, and I be very surprised if no-one has really tried the ZigBee binding under Windows at least once or twice in the past few years 
I agree, but I Tested already on 3 different Windows machines (although they are all running Windows 10 with recent Patch level). I tested with Oracle Java and Zulu, I tested with OH 2.5.7 und 2.5.9 release…
So far not functional. And I searched a lot of old forum posts, and I found NO ONE ever confirming that it works on Windows (but a lot of Raspi/Linux-Users who are not sure if this will work on Windows).
However, I just started with Zigbee, so hopefully I am missing something…but as I already use several other bindings successfully I am really puzzled that Zigbee already starts struggling with the integration of the stick/coordinator in a Windows envrionment…
Perhaps I should start a new topic and ask if anyone is running Zigbee in Windows environment…
For sure, I’ve tested, and am regularly using, the zigbee libraries under Windows. The binding is effectively the same functionality.
Here’s one that looks like uses Windows, and also with the Ember chipset. This post is from 10 days ago -:
Ok, I missed this one. However the guy is using a HUSBZB-1 stick (US-model that combines Z-Wave und Zigbee). I tried the Bitron Video and the Elelabs ELU013.
As I live in Germany, the US stick is no real option (and not available in any online shops as it seems to be discontinued). So it might be the stick model.
Another difference is that I tried to include the stick via PaperUI while in the Post a textual config file is used. Might this make a difference?
Which stick do you use when you are doing your testing in a Windows environment? Then I will order this stick also. Thank you for all the help!
Dear Vossivossi,
I had the zigbee stick bitron video running on my system, but very unstable.
So I decided to buy a philips hue for all my light stuff and replaced this usb dongle.
As I do not have further zigbee devices, I am satisfied with this solution.
The bitron I can not recommend, because sometimes my whole pc system stopped working even if the usb stick was not in operation…
Ottoson
Thank you for that post. So you had the stick running in a Windows environment? Where did you get the drivers from? The bitron video stick is not recognized on my Windows 10 machine and I cannot find any drivers in the internet:
Which driver did you use on Windows 10?
This is the SAME chipset fundamentally. The binding treats it EXACTLY the same - ie absolutely NO difference. The only difference possibly is the driver in Windows, but that is not related to the binding.
I use a number of devices - not sticks that you can order - EFR32 based mostly, but also some of the older chipsets.
I know that it also doesn’t work on the Mac since the BV uses non-standard USD product IDs. I don’t know if it works on Windows or not though.
That however should not be an issue with the Elelabs that you’re using.
Thank you for the clarification of the chipset. Actually I do not blame the binding, I am sure it is a Windows driver issue.
No, it is even only displayed as unknown device in Windows device manager.