Noob question about getting Z Wave working

I successful installed Hassio on a RaspberryPi, and am excited to get started. The first problem is that I can’t see my z wave usb stick. /dev/ttyACM0 is not listed in the supervisor. I’m guessing I need to do something on the host system to attach the usb device to the docker, but I can’t figure out home to get onto the host system. I know I can type login from the terminal but the credentials I made for HA don’t work there. Any help would be appreciated

You don’t have to anything, the supervisor already passes the serial devices to the containers.

You didn’t mention which Raspberry Pi and which USB stick you are using. The HUSBZB-1 would be /dev/ttyUSB0. The Aeotec and friends would be /dev/ttyACM0, or preferably /dev/serial/by-id/usb-0658_0200-if00. See https://www.home-assistant.io/hassio/zwave/#finding-the-path

If you have an Aeotec and RPi4 there is a known issue which requires a USB hub.

1 Like

Thanks that is helpful. I do have Aeotec + RPi4. Do I need to use a hub or can I manually configure it?

Use a hub. If there was another fix it would have been posted. https://alerts.home-assistant.io/#zwave_pi4_aeotec.markdown

Thanks, ordered it. Should come on Friday. So is there no way/reason to connect to the host system?

Probably need to install the ssh addon or login as root on the console.

Okay so I ordered this USB 2.0 hub:
https://www.amazon.com/gp/product/B00BWF5U0M/ref=ppx_yo_dt_b_asin_title_o00_s00?ie=UTF8&psc=1

I plugged it into one of the USB 2.0 plugs, manually added into integrations, and clicked start in the z-wave panel and this is what the log shows:

2020-04-02 16:44:02.813 Always, OpenZwave Version 1.4.3469 Starting Up
2020-04-02 16:44:24.027 Info, Setting Up Provided Network Key for Secure Communications
2020-04-02 16:44:24.028 Info, mgr,     Added driver for controller /dev/ttyAMA0
2020-04-02 16:44:24.028 Info,   Opening controller /dev/ttyAMA0
2020-04-02 16:44:24.028 Info, Trying to open serial port /dev/ttyAMA0 (attempt 1)
2020-04-02 16:44:24.028 Info, Serial port /dev/ttyAMA0 opened (attempt 1)
2020-04-02 16:44:24.029 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-02 16:44:24.029 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-04-02 16:44:24.029 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-04-02 16:44:24.029 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-04-02 16:44:24.029 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-04-02 16:44:24.029 Detail,
2020-04-02 16:44:24.029 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-02 16:44:24.029 Detail, contrlr, Notification: DriverFailed

/dev/ttyAMA0 is not the right path.

The lights are on the Aeotec Z wave controller. Is there something else I need to do?

Doesn’t look like the USB stick was detected. Is there something else plugged into the hub? Try a different port on the PI or Hub?

I tried it a few different ways but ultimately what worked was to plug the hub into the 3.0 port, and reboot the RPi. Thanks for the help!

Good to know, thanks for the update. :+1:

Had everything working last night, but woke up this morning and the few z-wave devices I added were not working. Looked in the logs and it looked like the controller was no longer responding. After a bunch of tinkering I rebooted the system, which seems to have fixed it for now.

I am concerned about the stability of the zwave network. Am I better off returning the Aeotec z-wave controller and getting the Zwave.me controller or is there something I can do to make the Aeotec controller work better? TIA

I don’t really think changing the controller is going to do anything. Aside from the Nortek stick, they are all basically the same. “The controller no longer responding” would be an unusual error. Perhaps you should upload the OZW_Log.txt file to a pastebin to see if there’s anything obvious.

The stability of the network depends on how well the mesh network is defined, location of the controller, etc, anything to minimize RF interference should be attempted. Moving the stick can help, putting it on an extension, etc. Do you have mains powered devices that will expand the mesh, or do you have only battery ones?

Right now there are only three devices attached. I should clarify that prior to rebooting, I tried to restart the z-wave network, and it would not start up. The logs said something to the effect that the controller could not be contacted. I think that there is something wrong with either the USB port of the RPi (unlikely since it occurs on different ports), the Aeotec controller, or the USB hub. I do have another zwave conroller (HomeSeer Z-stick) from the HomeSeer server I am migrating from in close proximity to the RPi. The majority of my devices are powered Z-wave lightswitches. I think I am going to return the controller and hub, and get the Zwave.me one to reduce the number of failure points, and for simplicity.

Anyway here is the OZW_log file Part 1:

2020-04-03 07:57:16.814 Always, OpenZwave Version 1.4.3469 Starting Up
2020-04-03 07:57:26.534 Info, Setting Up Provided Network Key for Secure Communications
2020-04-03 07:57:26.535 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2020-04-03 07:57:26.535 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2020-04-03 07:57:26.535 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2020-04-03 07:57:26.540 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2020-04-03 07:57:26.540 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-03 07:57:26.540 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-04-03 07:57:26.540 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-04-03 07:57:26.540 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-04-03 07:57:26.540 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-04-03 07:57:26.540 Detail,
2020-04-03 07:57:26.540 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-03 07:57:26.541 Detail, Unsolicited message received while waiting for ACK.
2020-04-03 07:57:26.542 Detail, Node005,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x05, 0x03, 0x26, 0x03, 0x28, 0xf9
2020-04-03 07:57:26.542 Detail,
2020-04-03 07:57:26.542 Detail, contrlr, CAN received...triggering resend
2020-04-03 07:57:26.542 Detail,
2020-04-03 07:57:26.542 Info, contrlr, Sending (Command) message (Attempt 2, Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-04-03 07:57:26.544 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x35, 0x34, 0x00, 0x01, 0x93
2020-04-03 07:57:26.544 Detail,
2020-04-03 07:57:26.544 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-04-03 07:57:26.544 Info, contrlr,     Static Controller library, version Z-Wave 4.54
2020-04-03 07:57:26.544 Detail, Node045,   Expected reply was received
2020-04-03 07:57:26.544 Detail, Node045,   Message transaction complete
2020-04-03 07:57:26.544 Detail,
2020-04-03 07:57:26.544 Detail, contrlr, Removing current message
2020-04-03 07:57:26.544 Detail,
2020-04-03 07:57:26.545 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-04-03 07:57:26.546 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xc3, 0xa6, 0x68, 0xc3, 0x01, 0x19
2020-04-03 07:57:26.546 Detail,
2020-04-03 07:57:26.546 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xc3a668c3.  Our node ID = 1
2020-04-03 07:57:26.547 Detail, Node166,   Expected reply was received
2020-04-03 07:57:26.547 Detail, Node166,   Message transaction complete
2020-04-03 07:57:26.547 Detail,
2020-04-03 07:57:26.547 Detail, contrlr, Removing current message
2020-04-03 07:57:26.547 Detail,
2020-04-03 07:57:26.547 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-04-03 07:57:26.548 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2020-04-03 07:57:26.548 Detail,
2020-04-03 07:57:26.548 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2020-04-03 07:57:26.548 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2020-04-03 07:57:26.548 Info, contrlr,     The PC controller is a primary controller.
2020-04-03 07:57:26.548 Detail, Node247,   Expected reply was received
2020-04-03 07:57:26.548 Detail, Node247,   Message transaction complete
2020-04-03 07:57:26.548 Detail,
2020-04-03 07:57:26.548 Detail, contrlr, Removing current message
2020-04-03 07:57:26.548 Detail,
2020-04-03 07:57:26.548 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-04-03 07:57:26.552 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
2020-04-03 07:57:26.552 Detail,
2020-04-03 07:57:26.552 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2020-04-03 07:57:26.552 Info, contrlr,     Serial API Version:   1.1
2020-04-03 07:57:26.552 Info, contrlr,     Manufacturer ID:      0x0086
2020-04-03 07:57:26.552 Info, contrlr,     Product Type:         0x0101
2020-04-03 07:57:26.552 Info, contrlr,     Product ID:           0x005a
2020-04-03 07:57:26.552 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-04-03 07:57:26.552 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-04-03 07:57:26.552 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-04-03 07:57:26.552 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-04-03 07:57:26.552 Detail, Node001,   Expected reply was received
2020-04-03 07:57:26.552 Detail, Node001,   Message transaction complete
2020-04-03 07:57:26.552 Detail,
2020-04-03 07:57:26.552 Detail, contrlr, Removing current message
2020-04-03 07:57:26.552 Detail,
2020-04-03 07:57:26.552 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-04-03 07:57:26.553 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2020-04-03 07:57:26.553 Detail,
2020-04-03 07:57:26.553 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2020-04-03 07:57:26.553 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2020-04-03 07:57:26.553 Detail, Node172,   Expected reply was received
2020-04-03 07:57:26.553 Detail, Node172,   Message transaction complete
2020-04-03 07:57:26.553 Detail,
2020-04-03 07:57:26.554 Detail, contrlr, Removing current message
2020-04-03 07:57:26.554 Detail,
2020-04-03 07:57:26.554 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2020-04-03 07:57:26.609 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x48, 0x9b, 0xbe, 0xdf, 0xcb, 0x8b, 0xe1, 0xc5, 0x40, 0x8f, 0xda, 0xc5, 0x5c, 0x5a, 0xfd, 0x11, 0x1a, 0x08, 0xa9, 0xfc, 0xbd, 0x8f, 0x08, 0x4a, 0x08, 0xf2, 0xd2, 0xfb, 0x5e, 0xf3, 0x15, 0xda, 0x8c
2020-04-03 07:57:26.609 Detail,
2020-04-03 07:57:26.609 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2020-04-03 07:57:26.609 Detail, Node032,   Expected reply was received
2020-04-03 07:57:26.609 Detail, Node032,   Message transaction complete
2020-04-03 07:57:26.609 Detail,
2020-04-03 07:57:26.609 Detail, contrlr, Removing current message
2020-04-03 07:57:26.609 Detail,
2020-04-03 07:57:26.609 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2020-04-03 07:57:26.714 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x39, 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, 0xfd
2020-04-03 07:57:26.714 Detail,
2020-04-03 07:57:26.714 Info, mgr,     Driver with Home ID of 0xc3a668c3 is now ready.
2020-04-03 07:57:26.714 Info,
2020-04-03 07:57:26.722 Info, Node004, CentralScene - Created 0
2020-04-03 07:57:26.722 Info, Node005, CentralScene - Created 0
2020-04-03 07:57:26.723 Info, Node006, CentralScene - Created 0
2020-04-03 07:57:26.725 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2020-04-03 07:57:26.725 Info, contrlr,     Node 001 - Known
2020-04-03 07:57:26.725 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-04-03 07:57:26.725 Detail, Node001, QueryStage_CacheLoad
2020-04-03 07:57:26.725 Info, Node001, Node Identity Codes: 0086:0101:005a
2020-04-03 07:57:26.725 Detail, Node001, QueryStage_Associations
2020-04-03 07:57:26.725 Detail, Node001, QueryStage_Neighbors
2020-04-03 07:57:26.725 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2020-04-03 07:57:26.725 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2020-04-03 07:57:26.725 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2020-04-03 07:57:26.725 Info, contrlr,     Node 004 - Known
2020-04-03 07:57:26.725 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-04-03 07:57:26.725 Detail, Node004, QueryStage_CacheLoad
2020-04-03 07:57:26.725 Info, Node004, Node Identity Codes: 000c:4447:3033
2020-04-03 07:57:26.725 Info, Node004, NoOperation::Set - Routing=true
2020-04-03 07:57:26.725 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcc
2020-04-03 07:57:26.725 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2020-04-03 07:57:26.725 Info, contrlr,     Node 005 - Known
2020-04-03 07:57:26.725 Detail, Node005, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-04-03 07:57:26.725 Detail, Node005, QueryStage_CacheLoad
2020-04-03 07:57:26.725 Info, Node005, Node Identity Codes: 000c:4447:3034
2020-04-03 07:57:26.725 Info, Node005, NoOperation::Set - Routing=true
2020-04-03 07:57:26.725 Detail, Node005, Queuing (NoOp) NoOperation_Set (Node=5): 0x01, 0x09, 0x00, 0x13, 0x05, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcc
2020-04-03 07:57:26.725 Detail, Node005, Queuing (Query) Query Stage Complete (CacheLoad)
2020-04-03 07:57:26.725 Info, contrlr,     Node 006 - Known
2020-04-03 07:57:26.725 Detail, Node006, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-04-03 07:57:26.725 Detail, Node006, QueryStage_CacheLoad
2020-04-03 07:57:26.725 Info, Node006, Node Identity Codes: 000c:4447:3034
2020-04-03 07:57:26.726 Info, Node006, NoOperation::Set - Routing=true
2020-04-03 07:57:26.726 Detail, Node006, Queuing (NoOp) NoOperation_Set (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x00, 0x00, 0x25, 0x0c, 0xc8
2020-04-03 07:57:26.726 Detail, Node006, Queuing (Query) Query Stage Complete (CacheLoad)
2020-04-03 07:57:26.726 Detail,   Expected reply was received
2020-04-03 07:57:26.726 Detail,   Message transaction complete
2020-04-03 07:57:26.726 Detail,
2020-04-03 07:57:26.726 Detail, contrlr, Removing current message
2020-04-03 07:57:26.726 Detail, Node001, Notification: DriverReady
2020-04-03 07:57:26.731 Detail, Node001, Notification: NodeAdded
2020-04-03 07:57:26.732 Detail, Node001, Notification: NodeProtocolInfo
2020-04-03 07:57:26.734 Detail, Node001, Notification: EssentialNodeQueriesComplete
2020-04-03 07:57:26.734 Detail, Node001, Notification: ValueAdded
2020-04-03 07:57:26.735 Detail, Node001, Notification: NodeNaming
2020-04-03 07:57:26.735 Detail, Node004, Notification: NodeAdded
2020-04-03 07:57:26.736 Detail, Node004, Notification: NodeProtocolInfo
2020-04-03 07:57:26.739 Detail, Node004, Notification: EssentialNodeQueriesComplete
2020-04-03 07:57:26.741 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.748 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.750 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.751 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.754 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.755 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.756 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.756 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.757 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.757 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.760 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.760 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.762 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.765 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.765 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.768 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.768 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.770 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.773 Detail, Node004, Notification: Group
2020-04-03 07:57:26.773 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.775 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.778 Detail, Node004, Notification: ValueAdded
2020-04-03 07:57:26.778 Detail, Node004, Notification: NodeNaming
2020-04-03 07:57:26.781 Detail, Node005, Notification: NodeAdded
2020-04-03 07:57:26.782 Detail, Node005, Notification: NodeProtocolInfo
2020-04-03 07:57:26.784 Detail, Node005, Notification: EssentialNodeQueriesComplete
2020-04-03 07:57:26.785 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.789 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.793 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.797 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.801 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.802 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.803 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.803 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.804 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.804 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.893 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.896 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.901 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.907 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.908 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.913 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.916 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.917 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.918 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.919 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.919 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.920 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.921 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.922 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.925 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.926 Detail, Node005, Notification: Group
2020-04-03 07:57:26.928 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.930 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.930 Detail, Node005, Notification: ValueAdded
2020-04-03 07:57:26.931 Detail, Node005, Notification: NodeNaming
2020-04-03 07:57:26.932 Detail, Node006, Notification: NodeAdded
2020-04-03 07:57:26.933 Detail, Node006, Notification: NodeProtocolInfo
2020-04-03 07:57:26.934 Detail, Node006, Notification: EssentialNodeQueriesComplete
2020-04-03 07:57:26.934 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.937 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.938 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.938 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.939 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.940 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.941 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.941 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.942 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.943 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.944 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.945 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.946 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.947 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.948 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.948 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.949 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.950 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.951 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.951 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.952 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.952 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.953 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.954 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.954 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.955 Detail, Node006, Notification: Group
2020-04-03 07:57:26.955 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.955 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.956 Detail, Node006, Notification: ValueAdded
2020-04-03 07:57:26.957 Detail, Node006, Notification: NodeNaming
2020-04-03 07:57:26.957 Detail,

Part 2 (removed a bunch of duplicate message in the middle.

2020-04-03 07:57:27.593 Detail, Node006, Removing current message
2020-04-03 07:57:27.593 Detail, Node006, Notification: ValueChanged
2020-04-03 07:57:27.596 Detail, Node004, Query Stage Complete (Dynamic)
2020-04-03 07:57:27.596 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-04-03 07:57:27.596 Detail, Node004, QueryStage_Configuration
2020-04-03 07:57:27.596 Detail, Node004, QueryStage_Complete
2020-04-03 07:57:27.596 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-04-03 07:57:27.596 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2020-04-03 07:57:27.596 Detail, Node004, Notification: NodeQueriesComplete
2020-04-03 07:57:27.597 Detail, Node005, Query Stage Complete (Dynamic)
2020-04-03 07:57:27.597 Detail, Node005, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-04-03 07:57:27.597 Detail, Node005, QueryStage_Configuration
2020-04-03 07:57:27.597 Detail, Node005, QueryStage_Complete
2020-04-03 07:57:27.597 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-04-03 07:57:27.597 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2020-04-03 07:57:27.598 Detail, Node005, Notification: NodeQueriesComplete
2020-04-03 07:57:27.599 Detail, Node006, Query Stage Complete (Dynamic)
2020-04-03 07:57:27.599 Detail, Node006, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-04-03 07:57:27.599 Detail, Node006, QueryStage_Configuration
2020-04-03 07:57:27.599 Detail, Node006, QueryStage_Complete
2020-04-03 07:57:27.599 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-04-03 07:57:27.599 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2020-04-03 07:57:27.599 Info,          Node query processing complete.
2020-04-03 07:57:27.599 Detail, Node006, Notification: NodeQueriesComplete
2020-04-03 07:57:27.601 Detail, contrlr, Notification: AllNodesQueried
2020-04-03 08:11:52.439 Info, Node004, NoOperation::Set - Routing=true
2020-04-03 08:11:52.439 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x19, 0xdf
2020-04-03 08:11:52.439 Info, Node005, NoOperation::Set - Routing=true
2020-04-03 08:11:52.439 Detail, Node005, Queuing (NoOp) NoOperation_Set (Node=5): 0x01, 0x09, 0x00, 0x13, 0x05, 0x02, 0x00, 0x00, 0x25, 0x1a, 0xdd
2020-04-03 08:11:52.439 Info, Node006, NoOperation::Set - Routing=true
2020-04-03 08:11:52.439 Detail, Node006, Queuing (NoOp) NoOperation_Set (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x00, 0x00, 0x25, 0x1b, 0xdf
2020-04-03 08:11:52.439 Detail,
2020-04-03 08:11:52.440 Info, Node004, Sending (NoOp) message (Callback ID=0x19, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x19, 0xdf
2020-04-03 08:11:53.440 Error, Node004, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:11:53.440 Detail, Node004, Removing current message
2020-04-03 08:11:53.440 Detail, Node004, Notification: Notification - TimeOut
2020-04-03 08:11:53.441 Detail,
2020-04-03 08:11:53.441 Info, Node005, Sending (NoOp) message (Callback ID=0x1a, Expected Reply=0x13) - NoOperation_Set (Node=5): 0x01, 0x09, 0x00, 0x13, 0x05, 0x02, 0x00, 0x00, 0x25, 0x1a, 0xdd
2020-04-03 08:11:54.441 Error, Node005, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:11:54.441 Detail, Node005, Removing current message
2020-04-03 08:11:54.441 Detail, Node005, Notification: Notification - TimeOut
2020-04-03 08:11:54.442 Detail,
2020-04-03 08:11:54.442 Info, Node006, Sending (NoOp) message (Callback ID=0x1b, Expected Reply=0x13) - NoOperation_Set (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x00, 0x00, 0x25, 0x1b, 0xdf
2020-04-03 08:11:55.442 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:11:55.442 Detail, Node006, Removing current message
2020-04-03 08:11:55.443 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:32.506 Info, Node006, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 255
2020-04-03 08:32:32.507 Info, Node006, SwitchMultilevel::Set - Setting to level 255
2020-04-03 08:32:32.507 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x1c, 0x02
2020-04-03 08:32:32.507 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x1d, 0xfd
2020-04-03 08:32:32.507 Detail,
2020-04-03 08:32:32.507 Info, Node006, Sending (Send) message (Callback ID=0x1c, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x1c, 0x02
2020-04-03 08:32:33.507 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:33.507 Detail, Node006, Removing current message
2020-04-03 08:32:33.507 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:33.508 Detail,
2020-04-03 08:32:33.508 Info, Node006, Sending (Send) message (Callback ID=0x1d, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x1d, 0xfd
2020-04-03 08:32:34.508 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:34.508 Detail, Node006, Removing current message
2020-04-03 08:32:34.508 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:39.088 Info, Node006, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 14
2020-04-03 08:32:39.088 Info, Node006, SwitchMultilevel::Set - Setting to level 14
2020-04-03 08:32:39.088 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0x0e, 0x25, 0x1e, 0xf1
2020-04-03 08:32:39.089 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x1f, 0xff
2020-04-03 08:32:39.089 Detail,
2020-04-03 08:32:39.089 Info, Node006, Sending (Send) message (Callback ID=0x1e, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0x0e, 0x25, 0x1e, 0xf1
2020-04-03 08:32:40.089 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:40.089 Detail, Node006, Removing current message
2020-04-03 08:32:40.089 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:40.090 Detail,
2020-04-03 08:32:40.090 Info, Node006, Sending (Send) message (Callback ID=0x1f, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x1f, 0xff
2020-04-03 08:32:41.090 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:41.090 Detail, Node006, Removing current message
2020-04-03 08:32:41.090 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:42.556 Info, Node006, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 255
2020-04-03 08:32:42.556 Info, Node006, SwitchMultilevel::Set - Setting to level 255
2020-04-03 08:32:42.556 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x20, 0x3e
2020-04-03 08:32:42.556 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x21, 0xc1
2020-04-03 08:32:42.561 Detail,
2020-04-03 08:32:42.562 Info, Node006, Sending (Send) message (Callback ID=0x20, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x20, 0x3e
2020-04-03 08:32:43.562 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:43.562 Detail, Node006, Removing current message
2020-04-03 08:32:43.562 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:43.563 Detail,
2020-04-03 08:32:43.563 Info, Node006, Sending (Send) message (Callback ID=0x21, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x21, 0xc1
2020-04-03 08:32:44.563 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:44.563 Detail, Node006, Removing current message
2020-04-03 08:32:44.563 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:44.627 Info, Node006, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 255
2020-04-03 08:32:44.627 Info, Node006, SwitchMultilevel::Set - Setting to level 255
2020-04-03 08:32:44.627 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x22, 0x3c
2020-04-03 08:32:44.627 Detail, Node006, Queuing (Send) SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x23, 0xc3
2020-04-03 08:32:44.627 Detail,
2020-04-03 08:32:44.627 Info, Node006, Sending (Send) message (Callback ID=0x22, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=6): 0x01, 0x0a, 0x00, 0x13, 0x06, 0x03, 0x26, 0x01, 0xff, 0x25, 0x22, 0x3c
2020-04-03 08:32:45.627 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:45.627 Detail, Node006, Removing current message
2020-04-03 08:32:45.627 Detail, Node006, Notification: Notification - TimeOut
2020-04-03 08:32:45.628 Detail,
2020-04-03 08:32:45.628 Info, Node006, Sending (Send) message (Callback ID=0x23, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x26, 0x02, 0x25, 0x23, 0xc3
2020-04-03 08:32:46.628 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-03 08:32:46.629 Detail, Node006, Removing current message

Replacing the Aeotec to get rid of the hub is valid, it’s the only stick that doesn’t work for the RPI4. The Zwave.me stick is just fine. Make sure your PI has a quality power supply, that is known to cause issues with USB serial ports.

Your problem is probably the CAN received...triggering resend error. It’s supposed to mean that there was a ZWave message collision caused by too much RF interference. Now, there have been a lot of posts lately encountering that problem, presumably with no issues before. The ERROR: Dropping command means the devices did not respond. As an experiment you might try moving the stick around and running a network heal to see if anything improves.

1 Like

Thanks for the advice. Any chance the other z-wave network is causing problems?

I suppose it’s possible, how many devices are on the other one? Multiple z-wave networks should be able to run at the same time, but they will compete with network bandwidth.

I have my “production” network with about 15 devices, and a test network with 3, and don’t have any issues. The controllers are in different rooms though.

I got the new controller, and it is working with out issue. Thank you @freshcoast for taking the time to help me.