OZW stuck on "Starting Z-Wave Network... This may take a while depending on the size of your network."

firstof9 · December 30, 2019, 4:10pm

Would need your log file too from when your network is stuck as well.

flyingsubs · December 30, 2019, 4:24pm

Sounds good. I’ll post the next time it happens. There isn’t any sensitive data if I post the first 20 lines, right?

flyingsubs · December 30, 2019, 5:53pm

2019-12-30 12:50:26.212 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 1
2019-12-30 12:50:26.212 Detail, Node173,   Expected reply was received
2019-12-30 12:50:26.212 Detail, Node173,   Message transaction complete
2019-12-30 12:50:26.212 Detail,
2019-12-30 12:50:26.212 Detail, contrlr, Removing current message
2019-12-30 12:50:26.212 Detail,
2019-12-30 12:50:26.212 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-12-30 12:50:26.268 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xa3, 0x9a, 0x72, 0x04, 0x75, 0xe0, 0x09, 0x52, 0x1d, 0xd0, 0xdb, 0xed, 0xcf, 0xaa, 0x20, 0xad, 0x8e, 0xa2, 0x2f, 0x13, 0x2d, 0xea, 0x36, 0x02, 0x48, 0xca, 0xcd, 0xad, 0x66, 0x4e, 0x2d, 0x40, 0x30
2019-12-30 12:50:26.268 Detail,
2019-12-30 12:50:26.268 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-12-30 12:50:26.268 Detail, Node032,   Expected reply was received
2019-12-30 12:50:26.268 Detail, Node032,   Message transaction complete
2019-12-30 12:50:26.268 Detail,
2019-12-30 12:50:26.268 Detail, contrlr, Removing current message
2019-12-30 12:50:26.268 Detail,
2019-12-30 12:50:26.268 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-12-30 12:50:26.373 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x08, 0x1d, 0x03, 0x7a, 0x08, 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, 0xbd
2019-12-30 12:50:26.373 Detail,
2019-12-30 12:50:26.373 Fatal, Failed to get HomeID or Controller Node ID during Init Sequence, m_homeId = 0x00000000, m_Controller_nodeId = 255
2019-12-30 12:50:26.373 Detail, Notification: DriverFailed

These are my home assistant log errors I got as well.

Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/components/zwave/__init__.py", line 892, in _finalize_start
    network.set_poll_interval(polling_interval, False)
  File "/usr/local/lib/python3.7/site-packages/openzwave/network.py", line 894, in set_poll_interval
    self.manager.setPollInterval(milliseconds, bIntervalBetweenPolls)
  File "/usr/local/lib/python3.7/site-packages/openzwave/network.py", line 564, in manager
    raise ZWaveException(u"Manager not initialised")
openzwave.object.ZWaveException: 'Zwave Generic Exception : Manager not initialised'

Then 300 seconds elapsed

Z-Wave Notification DriverFailed : {'notificationType': 'DriverFailed', 'homeId': 0, 'nodeId': 0}

Other thing to note, i’m using a raspberry pi 4 running docker. I also have an AEON Gen Stick 5 plugged into a USB hub because there is a USB issue plugging directly in

firstof9 · December 30, 2019, 6:10pm

Missing some lines from the beginning of your OZW_Log.txt

flyingsubs · December 30, 2019, 6:13pm

Strange, had to open the file manually.

2019-12-30 12:50:08.892 Always, OpenZwave Version 1.4.3452 Starting Up
2019-12-30 12:50:16.194 Info, Setting Up Provided Network Key for Secure Communications
2019-12-30 12:50:16.194 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2019-12-30 12:50:16.194 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2019-12-30 12:50:16.194 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2019-12-30 12:50:16.197 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2019-12-30 12:50:16.197 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-12-30 12:50:16.197 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-12-30 12:50:16.197 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-12-30 12:50:16.197 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-12-30 12:50:16.197 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-12-30 12:50:16.197 Detail, 
2019-12-30 12:50:16.197 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-12-30 12:50:16.199 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x35, 0x34, 0x00, 0x01, 0x93
2019-12-30 12:50:16.199 Detail, 
2019-12-30 12:50:16.199 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2019-12-30 12:50:16.199 Info, contrlr,     Static Controller library, version Z-Wave 4.54
2019-12-30 12:50:16.199 Detail, Node045,   Expected reply was received
2019-12-30 12:50:16.200 Detail, Node045,   Message transaction complete
2019-12-30 12:50:16.200 Detail, 
2019-12-30 12:50:16.200 Detail, contrlr, Removing current message
2019-12-30 12:50:16.200 Detail, 
2019-12-30 12:50:16.200 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-12-30 12:50:16.702 Warning, WARNING: 500ms passed without reading the rest of the frame...aborting frame read
2019-12-30 12:50:17.205 Warning, WARNING: 500ms passed without reading the rest of the frame...aborting frame read
2019-12-30 12:50:17.205 Warning, WARNING: Out of frame flow! (0xeb).  Sending NAK.
2019-12-30 12:50:17.709 Warning, WARNING: 500ms passed without reading the rest of the frame...aborting frame read
2019-12-30 12:50:17.709 Warning, WARNING: Out of frame flow! (0xeb).  Sending NAK.
2019-12-30 12:50:17.710 Detail, contrlr,   Received: 0x01, 0x01, 0x20
2019-12-30 12:50:17.710 Warning, contrlr, WARNING: Checksum incorrect - sending NAK
2019-12-30 12:50:17.711 Warning, WARNING: Out of frame flow! (0x12).  Sending NAK.
2019-12-30 12:50:26.201 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-30 12:50:26.201 Detail, contrlr, Removing current message
2019-12-30 12:50:26.201 Detail, contrlr, Notification: Notification - TimeOut
2019-12-30 12:50:26.202 Detail, 
2019-12-30 12:50:26.205 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-12-30 12:50:26.206 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x1c, 0xe3
2019-12-30 12:50:26.206 Detail, 
2019-12-30 12:50:26.206 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-12-30 12:50:26.206 Info, contrlr,     There is a SUC ID Server (SIS) in this network.
2019-12-30 12:50:26.206 Info, contrlr,     The PC controller is an inclusion static update controller (SUC) and was the original primary before the SIS was added.
2019-12-30 12:50:26.206 Detail, Node227,   Expected reply was received
2019-12-30 12:50:26.206 Detail, Node227,   Message transaction complete
2019-12-30 12:50:26.206 Detail, 
2019-12-30 12:50:26.206 Detail, contrlr, Removing current message
2019-12-30 12:50:26.206 Detail, 
2019-12-30 12:50:26.206 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-12-30 12:50:26.210 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x01, 0x00, 0x86, 0x01, 0x01, 0x00, 0x5a, 0xfe, 0x81, 0xff, 0x88, 0x4f, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x00, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x60, 0x00, 0x00, 0xfb
2019-12-30 12:50:26.210 Detail, 
2019-12-30 12:50:26.210 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-12-30 12:50:26.210 Info, contrlr,     Serial API Version:   1.1
2019-12-30 12:50:26.210 Info, contrlr,     Manufacturer ID:      0x0086
2019-12-30 12:50:26.210 Info, contrlr,     Product Type:         0x0101
2019-12-30 12:50:26.210 Info, contrlr,     Product ID:           0x005a
2019-12-30 12:50:26.210 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-12-30 12:50:26.210 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-12-30 12:50:26.210 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-12-30 12:50:26.210 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-12-30 12:50:26.210 Detail, Node001,   Expected reply was received
2019-12-30 12:50:26.210 Detail, Node001,   Message transaction complete
2019-12-30 12:50:26.210 Detail, 
2019-12-30 12:50:26.210 Detail, contrlr, Removing current message
2019-12-30 12:50:26.210 Detail,

firstof9 · December 30, 2019, 6:15pm

These errors indicate you have another program accessing the Zwave stick.
What other applications are you running?

flyingsubs · December 30, 2019, 6:19pm

Thank you for the response! I am unaware that I’m running another program running on the Pi. Is there anyway I can see what is running?

firstof9 · December 30, 2019, 6:37pm

Are you using HassOS?
If so, what addons do you have?

If not, how are you running Home Assistant?

flyingsubs · December 30, 2019, 6:52pm

I’m running Hass.io using docker

firstof9 · December 30, 2019, 7:06pm

Do you have any of the addons installed?
Is your host system by chance ubuntu?

flyingsubs · December 30, 2019, 7:14pm

I have Duck DNS, Backup Drive, Mosquitto broker and Pihole. I also have HACS with Bad Nest and Logbook installed. I’m running Raspbian Buster. I don’t have anything z-wave addon related to my knowledge

firstof9 · December 30, 2019, 7:35pm

Try giving this command a shot: sudo systemctl disable ModemManager.service

flyingsubs · December 30, 2019, 8:52pm

Okay! I’ll see how it performs in the future. Thank you!

flyingsubs · December 31, 2019, 9:40pm

Seems to be working after many restarts! Thank you so much!

Murremurray · January 6, 2020, 2:05pm

I haven’t been able to even start my zwave network up. It’s getting stucked and not able to start it up.

Installed HassOS a couple of days ago, and don’t seems to get it to work.
Running a raspberry pi 4 with Z-Wave.Me ZME_UZB1 USB Stick.

Can someone point me in the right direction?

System:
|arch|armv7l|
| --- | --- |
|dev|false|
|docker|true|
|hassio|true|
|os_name|Linux|
|python_version|3.7.5|
|timezone|Europe/Stockholm|
|version|0.103.5|
|virtualenv|false|

### Lovelace

mode auto-gen

2020-01-06 14:42:25.771 Always, OpenZwave Version 1.4.3452 Starting Up
2020-01-06 14:42:30.821 Info, Setting Up Provided Network Key for Secure Communications
2020-01-06 14:42:30.822 Info, mgr,     Added driver for controller /dev/ttyACM1
2020-01-06 14:42:30.822 Info,   Opening controller /dev/ttyACM1
2020-01-06 14:42:30.822 Info, Trying to open serial port /dev/ttyACM1 (attempt 1)
2020-01-06 14:42:30.824 Info, Serial port /dev/ttyACM1 opened (attempt 1)
2020-01-06 14:42:30.824 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-01-06 14:42:30.825 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-01-06 14:42:30.825 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-01-06 14:42:30.825 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-01-06 14:42:30.825 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-01-06 14:42:30.825 Detail,
2020-01-06 14:42:30.825 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-01-06 14:42:30.881 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:31.881 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-01-06 14:42:31.881 Detail, contrlr, Removing current message
2020-01-06 14:42:31.881 Detail, contrlr, Notification: Notification - TimeOut
2020-01-06 14:42:31.882 Detail,
2020-01-06 14:42:31.882 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-01-06 14:42:32.625 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:33.625 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-01-06 14:42:33.625 Detail, contrlr, Removing current message
2020-01-06 14:42:33.625 Detail, contrlr, Notification: Notification - TimeOut
2020-01-06 14:42:33.626 Detail,
2020-01-06 14:42:33.626 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-01-06 14:42:34.222 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:35.222 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-01-06 14:42:35.222 Detail, contrlr, Removing current message
2020-01-06 14:42:35.222 Detail, contrlr, Notification: Notification - TimeOut
2020-01-06 14:42:35.223 Detail,
2020-01-06 14:42:35.223 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-01-06 14:42:36.224 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-01-06 14:42:36.224 Detail, contrlr, Removing current message
2020-01-06 14:42:36.224 Detail, contrlr, Notification: Notification - TimeOut
2020-01-06 14:42:36.298 Detail,
2020-01-06 14:42:36.298 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-06 14:42:36.326 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:37.326 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-01-06 14:42:37.327 Detail, contrlr, Removing current message
2020-01-06 14:42:37.327 Detail, contrlr, Notification: Notification - TimeOut
2020-01-06 14:42:39.129 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:40.178 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:41.229 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:44.381 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:46.129 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK.
2020-01-06 14:42:53.131 Warning, WARNING: Out of frame flow! (0xc0).  Sending NAK

This continues…

firstof9 · January 6, 2020, 4:06pm

Review the documentation at this link:

Henrik1986 · January 21, 2020, 6:05am

I have hade the same issue and this worked for me.

hassio host reboot
hassio homeassistant restart

virtualistic · June 29, 2020, 8:09pm

@Henrik1986 I had the same thing happen out of the blue. One day it was working the next day it stopped.
Rebooting the host alone did not help, had indeed restart HA after rebooting the host. really strange.

Fabien31 · September 2, 2020, 1:34pm

Hi,

I’m new in Home Assistant with hassio.
i had the same issue because i misunderstood the documentation and the software.

I thought we had to install and configure the “Zwave” addon and also configure the Zwave integration.

When you configure and start the Zwave Addon, you cannot start the Zwave Network because the addon bind the zwave Stick.
Stop the zwave addon and configure the Zwave Integration.

It’s worked for me. Hope that w’ll help you

ltoolio · November 14, 2020, 5:41pm

Realized I never updated things on my end.

Everything has been (knock on wood) rock solid. No issues. Nada. I’ve more than doubled my zwave footprint and all is good.

No clue what triggered it.