Error 0x79 zwave binding zstick

paul42 · January 23, 2021, 12:48am

Win10 OH3 java11

OH3 is a clean install and very minimal as I’m just starting.

Error 0x79 at src/windows/termios.c(1323): The semaphore timeout period has expWrite action failed! Input/output error

I’m using a zstick for my zwave devices and I keep getting the above error on the console and my zstick will go off-line and stays that way

It not for every zwave action I perform, but it will eventually bring the stick off-line - It is impossiable to keep OH running for 24 hours.

I think it will happen even without me performing zwave actions - Cannot wakeup to a functioning OH.

What I want is for my zsick to work.

I don’t remember this with OH 2.5M1 and certainly never considered throwing my stick away and buying another one.

The flashing LED on the stick will also freeze at times.

Anyone else encountered this or know how to fix it - It’s not fixed in the latest snapshot for 3.1.

The stick in this log keeps going off-line and then back on again.

2021-01-23 02:06:59.885 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/London'.
2021-01-23 02:07:00.026 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ','.
2021-01-23 02:07:00.026 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_GB'.
2021-01-23 02:07:18.426 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2021-01-23 02:07:21.349 [INFO ] [org.openhab.ui.internal.UIService   ] - Started UI on port 8080
2021-01-23 02:07:22.024 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2021-01-23 02:07:25.411 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'hue:bridge:001788401d55' to inbox.
2021-01-23 02:07:25.926 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2021-01-23 02:07:27.114 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 6afa4f3f-f1ae-475e-8e8e-7a1b77a21ce1, base URL = http://localhost:8080)
2021-01-23 02:07:29.651 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2021-01-23 02:07:36.168 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:07:36.168 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:07:39.365 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:07:41.318 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:07:42.051 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 11: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:08:15.318 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:08:16.015 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 36: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@3bd4ba60[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@7ed63bde] rejected from java.util.concurrent.ThreadPoolExecutor@3df93694[Shutting down, pool size = 23, active threads = 23, queued tasks = 0, completed tasks = 18]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:378) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:08:26.187 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:08:26.187 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:08:29.415 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:08:35.457 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:08:35.457 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:08:38.682 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:08:44.756 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:08:44.756 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:08:47.979 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:08:54.073 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:08:54.073 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:08:54.292 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:08:57.932 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:08:58.142 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 11: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:09:47.322 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:09:48.895 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@2c0eb0ae[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@28313170] rejected from java.util.concurrent.ThreadPoolExecutor@690c3b4b[Shutting down, pool size = 20, active threads = 20, queued tasks = 0, completed tasks = 34]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:09:50.164 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 11: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@6396dee8[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@67a946c5] rejected from java.util.concurrent.ThreadPoolExecutor@690c3b4b[Shutting down, pool size = 20, active threads = 20, queued tasks = 0, completed tasks = 34]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:09:51.586 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 36: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@31fc7040[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@35c3e220] rejected from java.util.concurrent.ThreadPoolExecutor@690c3b4b[Shutting down, pool size = 20, active threads = 20, queued tasks = 0, completed tasks = 34]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:09:58.131 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:09:58.131 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:10:01.381 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:10:07.065 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:10:07.065 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:10:10.304 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:10:16.346 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:10:16.346 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:10:19.585 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:10:25.401 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:10:25.401 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:10:25.432 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:10:29.994 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:10:29.994 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 11: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:11:04.342 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:11:05.018 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 54: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@4ce8dc63[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@48d741a1] rejected from java.util.concurrent.ThreadPoolExecutor@9ea4b9a[Shutting down, pool size = 23, active threads = 23, queued tasks = 0, completed tasks = 20]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:378) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:11:15.246 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:11:15.246 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:11:18.479 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:11:24.526 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:11:24.526 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:11:27.759 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:11:33.697 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:11:33.697 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:11:36.916 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:11:42.932 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:11:42.932 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:11:44.292 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:11:46.366 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 11: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:11:46.694 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:12:14.690 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:12:16.048 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@6d9debd0[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@69a2723c] rejected from java.util.concurrent.ThreadPoolExecutor@289f7807[Shutting down, pool size = 21, active threads = 21, queued tasks = 0, completed tasks = 30]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:378) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:12:17.149 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@3b4f0e4a[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@3f709d7c] rejected from java.util.concurrent.ThreadPoolExecutor@289f7807[Shutting down, pool size = 21, active threads = 21, queued tasks = 0, completed tasks = 30]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:378) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:12:25.842 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:12:25.858 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:12:29.120 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:12:34.949 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:12:34.949 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:12:38.182 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:12:43.948 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:12:43.948 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:12:44.166 [WARN ] [WaveSerialHandler$ZWaveReceiveThread] - Exception during send
java.io.IOException: Input/output error in writeByte
	at gnu.io.RXTXPort.writeByte(Native Method) ~[?:?]
	at gnu.io.RXTXPort$SerialOutputStream.write(RXTXPort.java:1203) ~[?:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.sendResponse(ZWaveSerialHandler.java:287) [bundleFile:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.run(ZWaveSerialHandler.java:312) [bundleFile:?]
2021-01-23 02:12:47.213 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:12:52.995 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:12:52.995 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:12:53.213 [WARN ] [WaveSerialHandler$ZWaveReceiveThread] - Exception during send
java.io.IOException: Input/output error in writeByte
	at gnu.io.RXTXPort.writeByte(Native Method) ~[?:?]
	at gnu.io.RXTXPort$SerialOutputStream.write(RXTXPort.java:1203) ~[?:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.sendResponse(ZWaveSerialHandler.java:287) [bundleFile:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.run(ZWaveSerialHandler.java:312) [bundleFile:?]
2021-01-23 02:12:56.241 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:13:02.061 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:13:02.076 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:13:04.276 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:13:05.479 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 11: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:13:05.948 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:13:18.514 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:68f778ef82:node11' to inbox.
2021-01-23 02:13:29.252 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:68f778ef82:node41' to inbox.
2021-01-23 02:13:29.647 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:68f778ef82:node53' to inbox.
2021-01-23 02:14:37.651 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:14:48.560 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:14:48.560 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:14:48.779 [WARN ] [WaveSerialHandler$ZWaveReceiveThread] - Exception during send
java.io.IOException: Input/output error in writeByte
	at gnu.io.RXTXPort.writeByte(Native Method) ~[?:?]
	at gnu.io.RXTXPort$SerialOutputStream.write(RXTXPort.java:1203) ~[?:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.sendResponse(ZWaveSerialHandler.java:287) [bundleFile:?]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.run(ZWaveSerialHandler.java:312) [bundleFile:?]
2021-01-23 02:14:51.791 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:14:57.760 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:14:57.760 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:14:57.792 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 41: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:14:59.276 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:15:01.869 [WARN ] [ve.internal.protocol.ZWaveController] - NODE 59: Restore from config: Error. Data invalid, ignoring config.
2021-01-23 02:16:59.825 [WARN ] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2021-01-23 02:16:59.894 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 37: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@68d0031[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@84aef48] rejected from java.util.concurrent.ThreadPoolExecutor@2b28438[Shutting down, pool size = 18, active threads = 18, queued tasks = 0, completed tasks = 48]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:17:01.048 [ERROR] [alization.ZWaveNodeInitStageAdvancer] - NODE 34: Error in initialization thread
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@669ab934[Not completed, task = org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$1TransactionWaiter@62a531b8] rejected from java.util.concurrent.ThreadPoolExecutor@2b28438[Shutting down, pool size = 18, active threads = 18, queued tasks = 0, completed tasks = 48]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355) ~[?:?]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransactionAsync(ZWaveTransactionManager.java:1191) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.sendTransaction(ZWaveTransactionManager.java:1203) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.sendTransaction(ZWaveController.java:532) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:291) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.processTransaction(ZWaveNodeInitStageAdvancer.java:264) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.doInitialStages(ZWaveNodeInitStageAdvancer.java:442) ~[bundleFile:?]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer$1.run(ZWaveNodeInitStageAdvancer.java:188) [bundleFile:?]
2021-01-23 02:17:11.022 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2021-01-23 02:17:11.022 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2021-01-23 02:18:20.775 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:24.023 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 48: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:24.273 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:29.291 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:34.274 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:39.276 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:44.275 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:49.287 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:54.270 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:18:59.274 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:19:04.291 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.
2021-01-23 02:19:09.288 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 43: Not initialized (ie node unknown), ignoring message.

Bruce_Osborne · January 23, 2021, 1:47pm

Did you follow the instructions in the binding documentation? To get the needed information from your system unfiltered DEBUG logs are needed. There is also a log viewer that may help.

paul42 · January 24, 2021, 3:01am

Yes I’ve assigned the com port correctly.

I don’t understand the documentation on logging so could you tell me how to do this please.

Do you think this will work with OH logs?

Bruce_Osborne · January 24, 2021, 11:45am

If shows step by step at the bottom of the documtation under the title when things do not go as expected.

Yes, that is also the new home for the database.
The developer and a couple of others are working on an enhanced fork of OH3 and moving it there got it on to better hosting.
As the viewer page says,

This site provides an online processor for openHAB Z-Wave log files. All processing is performed on your own computer and no data is transferred to the remote server during processing unless specifically stated.

paul42 · January 24, 2021, 11:33pm

Do you have a url for that as I would like to read it.

However, I think they have removed it as I’ve looked at the bottom of the pages and done a CTRL-F using ‘expected’ but no go

The page on logging certainly does not have it logging :((

I did find the log4j2.xml conf file and inserted the line:

‘’

But this turned off all logging apart from the first 3 lines

However I did find the line:

log:set DEBUG org.openhab.binding.zwave

So I copied that to the console and ran OH:

CANNOT POST LOG FILE AS THERE IS A SIZE LIMIT TO POSTS ::

Loaded this into Z-Wave Log Viewer and it looked to work but tells me nothing really.

Point to note is that this is openHAB,log not a zwave log or are they the same thing?

Another intresting thing is that I continued to get error 0x79 on the consoule but they did not appear in the log file.

I wondering is the bug (so many of them) in the OH software for the zstick?

Is the OH zstick SW part of the zwave binding?

Any thoughts on how I should progress?

paul42 · January 24, 2021, 11:39pm

Double Post

Bruce_Osborne · January 24, 2021, 11:43pm

I said the BINDING documentation.

paul42 · January 29, 2021, 11:19pm

R right thanks, I was going round and round https://openhab.org/docs/

I’ve come up with a work round, I’ve factory reset my zstick!

This means I cannot reproduce the bug, so I would imagine that it cannot be reported - Sorry. However, I will write it up below for the next person.

Thanks for your help.

It all worked OK in OH 2.5M1 but not in 3.0 therefore it is a regression bug?
This thread is worth a read:

“Let me first bump the Z-Stick’s internal timeouts a bit. Or maybe it needs to be the opposite, to have it give up faster on non-responsive units. I’ll have to see what it’s set to currently and we can play with adjusting that.”

I’ve attached an OH log - openhab.log (167.4 KB)
There is a bug in the code that displays the error message on the console in that there is no newline at the end of the message. Therefore, the messages run across the screen rather than down it.

I suspect this is the case for all 0x errors as 0x16 IIRC has the same problem.

Bruce_Osborne · January 29, 2021, 11:28pm

There were huge changes between 2.5M1 & 2.5M2. Why were you still running a version that old? 2.5.x Stable was out for a year! Milestones,were known to have issues.

There were even more architectural changes for 3.0

I am glad you got it working again for you. I assume you are running 3.0.1 which has patches for 3.0 ?

paul42 · January 29, 2021, 11:49pm

I was using 2.5M1 as it was the most recent when I last played with OH 2ish? years ago after which I took 1-2 year break.

Glad I took the break OH3 is really nice.

Still on OH 3.0 as I was waiting for 3M1 this weekend as there is a flaw in the zwave binding which causes security warning messages to be displayed on the console, and it was fixed in a snapshoot I tried.

However, I see 3.0.1 has bug fixes as well as security fixes, so I will spin that up later this evening.