Controller status: Controller is unresponsive

[soleen]

Checklist

• I have read and followed the above instructions

• I have checked the troubleshooting section and my problem is not described there.

• I have read the changelog and my problem was not mentioned there or the fix did not work.

Describe the issue

Recently, my z-wave network stopped working correctly.

Hardware: Home Assistant Blue (ODROID-N2+)
Z-Wave: Zooz 800 Series Z-Wave Long Range S2 USB Stick ZST39 LR, plugged in directly into USB slot.
Core: 2024.6.1, Supervisor: 2024.06.0, Operating System 12.3
Z-Wave JS UI: 3.7.2
Zooz Firmware: SDK 7.21.3

After a full reboot Z-Wave works for about an hour, but after that it becomes unresponsive. The logs show the following messages:

2024-06-11 00:09:09.654 DEBUG SOCKET: User disconnected from 5VV1R7w-um66RCSTAAGX: ping timeout
2024-06-11 00:09:09.655 INFO Z-WAVE: Removing user callbacks
2024-06-11T04:09:10.032Z CNTRLR   The controller is unresponsive
2024-06-11 00:09:10.033 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-11T04:09:10.034Z DRIVER   Attempting to recover unresponsive controller by restarting it...
2024-06-11T04:09:10.034Z CNTRLR   Performing soft reset...
2024-06-11T04:09:11.038Z CNTRLR   Failed to execute controller command after 1/3 attempts. Scheduling next try i
                                  n 100 ms.
2024-06-11T04:09:12.140Z CNTRLR   Failed to execute controller command after 2/3 attempts. Scheduling next try i
                                  n 1100 ms.
2024-06-11T04:09:14.243Z CNTRLR   Soft reset failed: Timeout while waiting for an ACK from the controller (ZW020
                                  0)
2024-06-11T04:09:14.244Z DRIVER   Attempting to recover unresponsive controller by reopening the serial port...
2024-06-11T04:09:15.248Z DRIVER   Serial port reopened. Returning to normal operation and hoping for the best...
2024-06-11T04:09:15.248Z CNTRLR   The controller is no longer unresponsive
2024-06-11 00:09:15.248 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-11 00:09:33.494 INFO APP: GET /health/zwave 301 0.817 ms - 191
2024-06-11T04:09:36.828Z CNTRLR   Failed to execute controller command after 1/3 attempts. Scheduling next try i
                                  n 100 ms.
2024-06-11T04:09:37.931Z CNTRLR   Failed to execute controller command after 2/3 attempts. Scheduling next try i
                                  n 1100 ms.
2024-06-11T04:09:40.033Z CNTRLR   The controller is unresponsive
2024-06-11 00:09:40.034 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-11T04:09:40.035Z DRIVER   Attempting to recover unresponsive controller by restarting it...
2024-06-11T04:09:40.035Z CNTRLR   Performing soft reset...
2024-06-11T04:09:41.040Z CNTRLR   Failed to execute controller command after 1/3 attempts. Scheduling next try i
                                  n 100 ms.
2024-06-11T04:09:42.142Z CNTRLR   Failed to execute controller command after 2/3 attempts. Scheduling next try i
                                  n 1100 ms.
2024-06-11T04:09:44.246Z CNTRLR   Soft reset failed: Timeout while waiting for an ACK from the controller (ZW020
                                  0)
2024-06-11T04:09:44.246Z DRIVER   Attempting to recover unresponsive controller by reopening the serial port...
2024-06-11T04:09:45.250Z DRIVER   Serial port reopened. Returning to normal operation and hoping for the best...
2024-06-11T04:09:45.250Z CNTRLR   The controller is no longer unresponsive
2024-06-11 00:09:45.251 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-11 00:10:03.571 INFO APP: GET /health/zwave 301 0.839 ms - 191
2024-06-11T04:10:06.827Z CNTRLR   Failed to execute controller command after 1/3 attempts. Scheduling next try i
                                  n 100 ms.
2024-06-11T04:10:07.930Z CNTRLR   Failed to execute controller command after 2/3 attempts. Scheduling next try i
                                  n 1100 ms.
2024-06-11T04:10:10.032Z CNTRLR   The controller is unresponsive
2024-06-11 00:10:10.033 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-11T04:10:10.034Z DRIVER   Attempting to recover unresponsive controller by restarting it...
2024-06-11T04:10:10.034Z CNTRLR   Performing soft reset...
2024-06-11T04:10:11.041Z CNTRLR   Failed to execute controller command after 1/3 attempts. Scheduling next try i
                                  n 100 ms.
2024-06-11T04:10:12.144Z CNTRLR   Failed to execute controller command after 2/3 attempts. Scheduling next try i
                                  n 1100 ms.

The logs in store: z-ui_2024-06-10.log.gz, contain the following data at the end right before the first error:

2024-06-10 23:29:07.538 INFO Z-WAVE: [Node 059] Metadata updated: 50-0-value-65537
2024-06-10 23:29:07.540 INFO Z-WAVE: [Node 059] Value updated: 50-0-value-65537 0.021 => 0.021 
2024-06-10 23:29:33.221 INFO Z-WAVE: [Node 060] Metadata updated: 50-0-value-65537
2024-06-10 23:29:33.225 INFO Z-WAVE: [Node 060] Value updated: 50-0-value-65537 426.488 => 426.496
2024-06-10 23:30:00.375 INFO Z-WAVE: [Node 019] Value updated: 37-0-targetValue false => false 
2024-06-10 23:30:00.378 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.547 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.633 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.680 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.731 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.739 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.772 INFO Z-WAVE: [Node 020] Value updated: 37-0-targetValue false => false 
2024-06-10 23:30:00.774 INFO Z-WAVE: [Node 020] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.809 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:00.927 INFO Z-WAVE: [Node 019] Value updated: 37-0-currentValue false => false 
2024-06-10 23:30:09.992 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:30:15.216 INFO Z-WAVE: Controller status: Controller is Ready 
2024-06-10 23:30:39.990 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:30:45.208 INFO Z-WAVE: Controller status: Controller is Ready 
2024-06-10 23:31:09.990 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:31:14.222 INFO Z-WAVE: Setting user callbacks
2024-06-10 23:31:14.533 INFO Z-WAVE: Removing user callbacks
2024-06-10 23:31:15.241 INFO Z-WAVE: Controller status: Controller is Ready 
2024-06-10 23:31:15.733 INFO Z-WAVE: Setting user callbacks
2024-06-10 23:31:39.995 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:31:45.219 INFO Z-WAVE: Controller status: Controller is Ready 
2024-06-10 23:31:47.108 INFO Z-WAVE: Removing user callbacks
2024-06-10 23:31:47.408 INFO Z-WAVE: Setting user callbacks
2024-06-10 23:31:47.719 INFO Z-WAVE: Removing user callbacks
2024-06-10 23:31:48.722 INFO Z-WAVE: Setting user callbacks
2024-06-10 23:32:09.990 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:32:15.210 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:32:39.991 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:32:45.213 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:33:09.991 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:33:15.213 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:33:23.634 INFO Z-WAVE: Removing user callbacks
2024-06-10 23:33:39.990 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:33:45.208 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:34:03.564 INFO Z-WAVE: Setting user callbacks
2024-06-10 23:34:09.993 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:34:15.214 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:34:39.996 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:34:45.220 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:35:09.992 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:35:15.211 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:35:40.003 INFO Z-WAVE: Controller status: Controller is unresponsive
2024-06-10 23:35:45.225 INFO Z-WAVE: Controller status: Controller is Ready
2024-06-10 23:36:09.997 INFO Z-WAVE: Controller status: Controller is unresponsive

Software versions

Driver (node-zwave-js): ...

Z-Wave JS UI: ...

Home Assistant Z-Wave Integration: ...

Home Assistant Z-Wave JS Addon: ...

ioBroker.zwave2 Adapter: ...

If you are using something non-standard, tell us here: ...

Device information

Manufacturer: ...
Model name: ...
Node ID: ...

Checklist

• I made sure to provide a driver log on level debug.

• The log includes a re-interview of the problematic device (if applicable).

• The log includes the problematic interaction with the device (if applicable).

• I provided the node ID of the problematic device (if applicable).

Upload Logfile

[
zwave-js-ui-store.zip
](url)

[kpine]

Did you upgrade the firmware recently? Try downgrading it. There's already a lengthy discussion for this topic at #6874.

[AlCalzone]

plugged in directly into USB slot.

kpine already gave you the answer, but for your own sake, please use a USB extension cable. This video is about Zigbee but applies the same for Z-Wave: https://www.youtube.com/watch?v=tHqZhNcFEvA