Z-Wave network not starting on HassIO VM on Unraid

Tags: #<Tag:0x00007f7392707f30> #<Tag:0x00007f7392707e40>

Hi All!

Hopefully someone can help me get to the bottom of what is going on here. I have the Aotec Z-Stick passed through by Unraid and it looks like my VM can see it just fine. For whatever reason though the network just won’t start. Am I overlooking something obvious? I have reset the Z-Stick to factory defaults to make sure there was no issue there and still am running into the same issue.

Unraid VM Configuration:

HA CLI:

~ $ lsusb
Bus 001 Device 001: ID 1d6b:0002
Bus 001 Device 002: ID 0658:0200
Bus 002 Device 001: ID 1d6b:0003
Bus 001 Device 003: ID 0627:0001
~ $ ha hardware info
audio: {}
disk: []
gpio: []
input:
- QEMU QEMU USB Tablet
- AT Translated Set 2 keyboard
- ImExPS/2 Generic Explorer Mouse
- Power Button
serial:
- /dev/ttyACM0
- /dev/serial/by-id/usb-0658_0200-if00
- /dev/ttyS1
- /dev/ttyS2
- /dev/ttyS3
- /dev/ttyS0
usb:
- /dev/bus/usb/001/001
- /dev/bus/usb/001/002
- /dev/bus/usb/001/003
- /dev/bus/usb/002/001

Here it looks like its showing up as /dev/ttyACM0 and /dev/serial/by-id/usb-0658_0200-if00. That ID matches the ID shown in the Unraid configuration as well.

OZW Log:

2020-10-20 13:56:43.912 Always, OpenZwave Version 1.4.3469 Starting Up
2020-10-20 13:56:50.121 Info, Setting Up Provided Network Key for Secure Communications
2020-10-20 13:56:50.121 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2020-10-20 13:56:50.128 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2020-10-20 13:56:50.129 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2020-10-20 13:57:00.310 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2020-10-20 13:57:00.311 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-10-20 13:57:00.311 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-10-20 13:57:00.311 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-10-20 13:57:00.311 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-10-20 13:57:00.311 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-10-20 13:57:00.311 Detail,
2020-10-20 13:57:00.311 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-10-20 13:57:01.311 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 13:57:01.311 Detail, contrlr, Removing current message
2020-10-20 13:57:01.311 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 13:57:01.311 Detail,
2020-10-20 13:57:01.311 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-10-20 13:57:02.312 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 13:57:02.312 Detail, contrlr, Removing current message
2020-10-20 13:57:02.312 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 13:57:02.312 Detail,
2020-10-20 13:57:02.312 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-10-20 13:57:03.312 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 13:57:03.312 Detail, contrlr, Removing current message
2020-10-20 13:57:03.312 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 13:57:03.312 Detail,
2020-10-20 13:57:03.312 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-10-20 13:57:04.313 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 13:57:04.313 Detail, contrlr, Removing current message
2020-10-20 13:57:04.313 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 13:57:04.313 Detail,
2020-10-20 13:57:04.313 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-10-20 13:57:05.313 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 13:57:05.313 Detail, contrlr, Removing current message
2020-10-20 13:57:05.313 Detail, contrlr, Notification: Notification - TimeOut

Finally, my configuration.yaml:

zwave:
  usb_path: /dev/serial/by-id/usb-0658_0200-if00

I’ve also tried using /dev/ttyACM0 here with no luck.

I’m no unraid expert but have you tried changing the USB to 2.0 instead of 3.0?

That seems to have gotten me a little further but I’m still timing out:

2020-10-20 19:39:03.633 Always, OpenZwave Version 1.4.3469 Starting Up
2020-10-20 19:39:09.550 Info, Setting Up Provided Network Key for Secure Communications
2020-10-20 19:39:09.551 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2020-10-20 19:39:09.562 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2020-10-20 19:39:09.562 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2020-10-20 19:39:32.403 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2020-10-20 19:39:32.403 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-10-20 19:39:32.403 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-10-20 19:39:32.403 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-10-20 19:39:32.403 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-10-20 19:39:32.403 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-10-20 19:39:32.403 Detail,
2020-10-20 19:39:32.403 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-10-20 19:39:32.407 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x33, 0x2e, 0x39, 0x35, 0x00, 0x01, 0x99
2020-10-20 19:39:32.407 Detail,
2020-10-20 19:39:32.407 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-10-20 19:39:32.407 Info, contrlr,     Static Controller library, version Z-Wave 3.95
2020-10-20 19:39:32.407 Detail, Node045,   Expected reply was received
2020-10-20 19:39:32.407 Detail, Node045,   Message transaction complete
2020-10-20 19:39:32.407 Detail,
2020-10-20 19:39:32.407 Detail, contrlr, Removing current message
2020-10-20 19:39:32.407 Detail,
2020-10-20 19:39:32.407 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-10-20 19:39:32.410 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xfd, 0x80, 0xb0, 0xb8, 0x01, 0xa2
2020-10-20 19:39:32.410 Detail,
2020-10-20 19:39:32.410 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfd80b0b8.  Our node ID = 1
2020-10-20 19:39:32.410 Detail, Node128,   Expected reply was received
2020-10-20 19:39:32.410 Detail, Node128,   Message transaction complete
2020-10-20 19:39:32.410 Detail,
2020-10-20 19:39:32.410 Detail, contrlr, Removing current message
2020-10-20 19:39:32.410 Detail,
2020-10-20 19:39:32.410 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-10-20 19:39:33.411 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 19:39:33.411 Detail, contrlr, Removing current message
2020-10-20 19:39:33.411 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 19:39:33.411 Detail,
2020-10-20 19:39:33.411 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-10-20 19:39:34.411 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 19:39:34.411 Detail, contrlr, Removing current message
2020-10-20 19:39:34.411 Detail, contrlr, Notification: Notification - TimeOut
2020-10-20 19:39:34.411 Detail,
2020-10-20 19:39:34.411 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-10-20 19:39:35.411 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-10-20 19:39:35.411 Detail, contrlr, Removing current message
2020-10-20 19:39:35.411 Detail, contrlr, Notification: Notification - TimeOut

Looks like your passthru for the USB is dropping out. I think someone had solved this issue on the forums here, try searching.

Is another VM/service/something using the device at the same time and thus locking it perhaps? Try a different USB port?

Maybe anything in this thread can help you figure this out?

Ended up getting it working by swapping the Z-Stick from the rear USB 3.0 ports on my motherboards to the front panel USB 2.0 ports on my case.

Ah similar to the rPi4 and Aeotec issue, likely ALL USB 3.0 ports are going to have this issue.