[SOLVED] Z-Wave initialisation continuously - OZW problem with HA

Hi everybody,

A couple of hours since I’m stuck in setting z-wave. I’ve read more then once the documentation and several topics here, but without any success. So, my turn to ask for your help.

I’ve received today my Sigma Designs Z-Wave Plus dongle, with some Eurotronic Spirit Z-Wave+ TRV. I’ve plugged the USB on my RPI3 (running Hass.IO), update the configuration.yaml and restart HA : OpenZWave start the network and … nothing. Like OZW is continuously starting the network.

Capture du 2020-01-23 22-53-33
(Sorry - still in French)

For information, initially I was running HA 0.103.x. After few hours I’ve upgrade to 0.104.3 to check if that could make any difference (spoiler alert : no).
I’ve also try the 3 differents “address” for the usb in the configuration.yaml :

/dev/ttyACM0
/dev/ttyAMA0
/dev/serial/by-id/usb-0658_0200_32303136-3131-3033-3030-303032343933-if00

But I still have the same log into OZW :

2020-01-23 22:37:10.131 Always, OpenZwave Version 1.4.3452 Starting Up
2020-01-23 22:37:43.250 Info, Setting Up Provided Network Key for Secure Communications
2020-01-23 22:37:43.250 Warning, Failed - Network Key Not Set
2020-01-23 22:37:43.250 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200_32303136-3131-3033-3030-303032343933-if00
2020-01-23 22:37:43.250 Info,   Opening controller /dev/serial/by-id/usb-0658_0200_32303136-3131-3033-3030-303032343933-if00
2020-01-23 22:37:43.250 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200_32303136-3131-3033-3030-303032343933-if00 (attempt 1)
2020-01-23 22:37:43.251 Info, Serial port /dev/serial/by-id/usb-0658_0200_32303136-3131-3033-3030-303032343933-if00 opened (attempt 1)
2020-01-23 22:37:43.252 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-01-23 22:37:43.252 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-01-23 22:37:43.252 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-01-23 22:37:43.252 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-01-23 22:37:43.252 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-01-23 22:37:43.252 Detail,
2020-01-23 22:37:43.252 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-01-23 22:37:43.255 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x36, 0x2e, 0x30, 0x31, 0x00, 0x01, 0x91
2020-01-23 22:37:43.255 Detail,
2020-01-23 22:37:43.255 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-01-23 22:37:43.255 Info, contrlr,     Static Controller library, version Z-Wave 6.01
2020-01-23 22:37:43.255 Detail, Node045,   Expected reply was received
2020-01-23 22:37:43.255 Detail, Node045,   Message transaction complete
2020-01-23 22:37:43.255 Detail,
2020-01-23 22:37:43.255 Detail, contrlr, Removing current message
2020-01-23 22:37:43.255 Detail,
2020-01-23 22:37:43.255 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-01-23 22:37:43.258 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xff, 0xff, 0xff, 0xfe, 0xef, 0x38
2020-01-23 22:37:43.259 Detail,
2020-01-23 22:37:43.259 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfffffffe.  Our node ID = 239
2020-01-23 22:37:43.259 Detail, contrlr,   Expected reply was received
2020-01-23 22:37:43.259 Detail, contrlr,   Message transaction complete
2020-01-23 22:37:43.259 Detail,
2020-01-23 22:37:43.259 Detail, contrlr, Removing current message
2020-01-23 22:37:43.259 Detail,
2020-01-23 22:37:43.259 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-01-23 22:37:43.261 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7
2020-01-23 22:37:43.261 Detail,
2020-01-23 22:37:43.261 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2020-01-23 22:37:43.261 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2020-01-23 22:37:43.261 Info, contrlr,     The PC controller is a primary controller.
2020-01-23 22:37:43.261 Detail, Node215,   Expected reply was received
2020-01-23 22:37:43.261 Detail, Node215,   Message transaction complete
2020-01-23 22:37:43.261 Detail,
2020-01-23 22:37:43.261 Detail, contrlr, Removing current message
2020-01-23 22:37:43.261 Detail,
2020-01-23 22:37:43.261 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-01-23 22:37:43.265 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x05, 0x0e, 0x00, 0x00, 0x00, 0x03, 0x00, 0x08, 0xfe, 0x87, 0x7f, 0x88, 0xcf, 0x7f, 0xc0, 0x4f, 0xfb, 0xdf, 0xfd, 0xe0, 0x67, 0x00, 0x80, 0x80, 0x00, 0x80, 0x86, 0x00, 0x01, 0x00, 0xe8, 0x73, 0x00, 0x80, 0x0f, 0x00, 0x00, 0x60, 0x00, 0x00, 0x4e
2020-01-23 22:37:43.266 Detail,
2020-01-23 22:37:43.266 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2020-01-23 22:37:43.266 Info, contrlr,     Serial API Version:   5.14
2020-01-23 22:37:43.266 Info, contrlr,     Manufacturer ID:      0x0000
2020-01-23 22:37:43.266 Info, contrlr,     Product Type:         0x0003
2020-01-23 22:37:43.266 Info, contrlr,     Product ID:           0x0008
2020-01-23 22:37:43.266 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-01-23 22:37:43.266 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-01-23 22:37:43.266 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-01-23 22:37:43.266 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-01-23 22:37:43.266 Detail, Node014,   Expected reply was received
2020-01-23 22:37:43.266 Detail, Node014,   Message transaction complete
2020-01-23 22:37:43.266 Detail,
2020-01-23 22:37:43.266 Detail, contrlr, Removing current message
2020-01-23 22:37:43.266 Detail,
2020-01-23 22:37:43.266 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-01-23 22:37:43.268 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2020-01-23 22:37:43.268 Detail,
2020-01-23 22:37:43.268 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2020-01-23 22:37:43.268 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2020-01-23 22:37:43.268 Detail, Node172,   Expected reply was received
2020-01-23 22:37:43.268 Detail, Node172,   Message transaction complete
2020-01-23 22:37:43.268 Detail,
2020-01-23 22:37:43.268 Detail, contrlr, Removing current message
2020-01-23 22:37:43.269 Detail,
2020-01-23 22:37:43.269 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-01-23 22:37:43.326 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xd8, 0xfb, 0xd1, 0xb2, 0x14, 0xac, 0x93, 0x26, 0x95, 0x1b, 0x28, 0x3c, 0x31, 0x79, 0xd6, 0xf7, 0x59, 0x99, 0xc9, 0x65, 0x18, 0x30, 0xf0, 0x7a, 0x32, 0xc8, 0xf2, 0x52, 0x09, 0xfa, 0xc9, 0x14, 0xe2
2020-01-23 22:37:43.326 Detail,
2020-01-23 22:37:43.326 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2020-01-23 22:37:43.326 Detail, Node032,   Expected reply was received
2020-01-23 22:37:43.326 Detail, Node032,   Message transaction complete
2020-01-23 22:37:43.326 Detail,
2020-01-23 22:37:43.326 Detail, contrlr, Removing current message
2020-01-23 22:37:43.326 Detail,
2020-01-23 22:37:43.327 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-01-23 22:37:43.424 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x08, 0x00, 0x1d, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x05, 0x00, 0xc9
2020-01-23 22:37:43.424 Detail,
2020-01-23 22:37:43.424 Info, mgr,     Driver with Home ID of 0xfffffffe is now ready.
2020-01-23 22:37:43.424 Info,
2020-01-23 22:37:43.424 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2020-01-23 22:37:43.424 Detail,   Expected reply was received
2020-01-23 22:37:43.424 Detail,   Message transaction complete
2020-01-23 22:37:43.424 Detail,
2020-01-23 22:37:43.424 Detail, contrlr, Removing current message
2020-01-23 22:37:43.424 Detail, Node239, Notification: DriverReady
2020-01-23 22:37:43.427 Detail,
2020-01-23 22:37:43.428 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-01-23 22:37:43.432 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2020-01-23 22:37:43.434 Detail,
2020-01-23 22:37:43.434 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2020-01-23 22:37:43.434 Detail, Node015,   Expected reply was received
2020-01-23 22:37:43.434 Detail, Node015,   Message transaction complete
2020-01-23 22:37:43.434 Detail,
2020-01-23 22:37:43.434 Detail, contrlr, Removing current message
2020-01-23 22:37:43.435 Detail,
2020-01-23 22:37:43.435 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-01-23 22:37:43.438 Detail, contrlr, Removing current message

If you have any idea on how to resolve this problem, I’m ready to hear it :slight_smile:

Very strange. I started having the same issue starting this morning. I’ve come up with zero possible solutions.

Are you running HassOS or Raspbian?

I’m running HASS from Docker. I’ve had HASS running from several years and usually a reboot fixes any Zwave issues that I’m having.

Personally, I’m on HassOS.
No problem on other integration.

@Jtmoore81 If your underlying OS is Debian/Ubuntu try apt-get purge modemmanager see if that helps your issue.

@letohicy Other than not having a network key set I don’t see any further errors in your log. Have you tried rebooting the host? (not restarting HassIO but HassOS)

I’ve tried several times to reboot HassOS with and w/o restarting HassIO. No influance.

For the network key, I didn’t manage it as my first goal was to make it working, before securing the network.

Tried a different USB port?

My HASS is running in a Synology box, I tried running the command and received an error message. I tried to see if Synology even has modemmanager installed and I don’t believe it does. It’s weird that it just stopped working without me making any changes. I had to reboot the Synology box this morning and that’s when the problem started.

I’ve also try that, but nothing change.

By reading the log, I have the impression that the problem isn’t with the dongle, but with OZW. Like it seems that the initialisation start well but something stop it or it can’t go further. That’s quite strange.

I tend to agree. Now to figure out why?

I think there’s been other with Synology having similar issues with zwave, try a forum search.

The log shows no errors so it should be working fine.

Yep. There is no error anywhere, but nothing is working :sweat:

I’ve checked the OZW log with the official OZW Log Analyzer : all seems correct. here
Hass.IO seems to be OK, and HassOS too.

Z-wave network is still starting, and I can’t had any node or TRV.
So now, I literally don’t know what to do / check / try. Any idea?

I’m out of ideas, there’s no reason it shouldn’t be working honestly.

1 Like

This suggest that something got corrupted in the controllerstick.
That home_id is invalid.

1 Like

I haven’t found any information on this.
Do you know how I can check (or correct) that?

Home id is generated at initial setup of the network. This is not editable. To my knowledge there is nothing else then to hard reset the stick and start from scratch.

Problem solved!!

Adding the first Z-Wave node (and the second one) with the dev panel - services menu was the solution.
As expected, the USB dongle nor the devices were the problem. HA seems to have some difficulties, when initializing a Z-Wave network, to update the Z-Wave panel/menu.

Thanks all for your time and help.