Z-Wave not working at all

stivoberlin · November 20, 2019, 7:13pm

I am new to Home Assistant. I can’t get my raspberry 3 B to work with any of my zwave devices. I have a Z-Stick Gen 5 USB Key. My usb stick is listed in the integration settings. But I can’t add any node. I only have the usb stick as a node and an unknown node. Impossible to add any other zwave device. Any clue about the problem ?

here is an extract of my log :

2019-11-20 19:23:14.453 Always, OpenZwave Version 1.4.3440 Starting Up
2019-11-20 19:23:24.558 Info, Setting Up Provided Network Key for Secure Communications
2019-11-20 19:23:24.559 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2019-11-20 19:23:24.559 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2019-11-20 19:23:24.559 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2019-11-20 19:23:24.560 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2019-11-20 19:23:24.560 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-11-20 19:23:24.561 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-11-20 19:23:24.561 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-11-20 19:23:24.561 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-11-20 19:23:24.561 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-11-20 19:23:24.561 Detail,
2019-11-20 19:23:24.561 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-11-20 19:23:24.563 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x35, 0x34, 0x00, 0x01, 0x93
2019-11-20 19:23:24.564 Detail,
2019-11-20 19:23:24.564 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2019-11-20 19:23:24.564 Info, contrlr,     Static Controller library, version Z-Wave 4.54
2019-11-20 19:23:24.564 Detail, Node045,   Expected reply was received
2019-11-20 19:23:24.564 Detail, Node045,   Message transaction complete
2019-11-20 19:23:24.564 Detail,
2019-11-20 19:23:24.564 Detail, contrlr, Removing current message
2019-11-20 19:23:24.564 Detail,
2019-11-20 19:23:24.564 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-11-20 19:23:24.566 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xf1, 0x91, 0x30, 0x55, 0x01, 0xd2
2019-11-20 19:23:24.566 Detail,
2019-11-20 19:23:24.566 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xf1913055.  Our node ID = 1
2019-11-20 19:23:24.567 Detail, Node145,   Expected reply was received
2019-11-20 19:23:24.567 Detail, Node145,   Message transaction complete
2019-11-20 19:23:24.567 Detail,
2019-11-20 19:23:24.567 Detail, contrlr, Removing current message
2019-11-20 19:23:24.567 Detail,
2019-11-20 19:23:24.567 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-11-20 19:23:24.568 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2019-11-20 19:23:24.568 Detail,
2019-11-20 19:23:24.568 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-11-20 19:23:24.568 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2019-11-20 19:23:24.568 Info, contrlr,     The PC controller is a primary controller.
2019-11-20 19:23:24.568 Detail, Node247,   Expected reply was received
2019-11-20 19:23:24.568 Detail, Node247,   Message transaction complete
2019-11-20 19:23:24.568 Detail,
2019-11-20 19:23:24.568 Detail, contrlr, Removing current message
2019-11-20 19:23:24.568 Detail,
2019-11-20 19:23:24.569 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-11-20 19:23:24.572 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x01, 0x00, 0x86, 0x00, 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, 0xfa
2019-11-20 19:23:24.572 Detail,
2019-11-20 19:23:24.572 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-11-20 19:23:24.572 Info, contrlr,     Serial API Version:   1.1
2019-11-20 19:23:24.572 Info, contrlr,     Manufacturer ID:      0x0086
2019-11-20 19:23:24.572 Info, contrlr,     Product Type:         0x0001
2019-11-20 19:23:24.572 Info, contrlr,     Product ID:           0x005a
2019-11-20 19:23:24.573 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-11-20 19:23:24.573 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-11-20 19:23:24.573 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-11-20 19:23:24.573 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-11-20 19:23:24.573 Detail, Node001,   Expected reply was received
2019-11-20 19:23:24.573 Detail, Node001,   Message transaction complete
2019-11-20 19:23:24.573 Detail,
2019-11-20 19:23:24.573 Detail, contrlr, Removing current message
2019-11-20 19:23:24.573 Detail,
2019-11-20 19:23:24.573 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-11-20 19:23:24.575 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2019-11-20 19:23:24.575 Detail,
2019-11-20 19:23:24.575 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2019-11-20 19:23:24.575 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2019-11-20 19:23:24.575 Detail, Node172,   Expected reply was received
2019-11-20 19:23:24.575 Detail, Node172,   Message transaction complete
2019-11-20 19:23:24.575 Detail,
2019-11-20 19:23:24.575 Detail, contrlr, Removing current message
2019-11-20 19:23:24.575 Detail,
2019-11-20 19:23:24.575 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-11-20 19:23:24.631 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xcc, 0xcb, 0xeb, 0xdc, 0xc2, 0x39, 0x67, 0xe8, 0xbc, 0x5f, 0x69, 0x36, 0xb8, 0xc8, 0x05, 0xd6, 0x16, 0x23, 0x6c, 0x83, 0x86, 0xd8, 0xd5, 0x8d, 0x77, 0x89, 0xce, 0x31, 0x91, 0x10, 0xbd, 0xe6, 0xba
2019-11-20 19:23:24.631 Detail,
2019-11-20 19:23:24.631 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-11-20 19:23:24.631 Detail, Node032,   Expected reply was received
2019-11-20 19:23:24.631 Detail, Node032,   Message transaction complete
2019-11-20 19:23:24.631 Detail,
2019-11-20 19:23:24.631 Detail, contrlr, Removing current message
2019-11-20 19:23:24.632 Detail,
2019-11-20 19:23:24.632 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-11-20 19:23:24.736 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x05, 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, 0xc1
2019-11-20 19:23:24.736 Detail,
2019-11-20 19:23:24.736 Info, mgr,     Driver with Home ID of 0xf1913055 is now ready.
2019-11-20 19:23:24.736 Info,
.... 
2019-11-20 19:36:00.449 Detail, contrlr, Removing current message
2019-11-20 19:36:00.449 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:36:00.449 Detail, Notification: ControllerCommand - Failed
2019-11-20 19:36:00.451 Detail,
2019-11-20 19:36:00.451 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:36:00.503 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0f, 0x06, 0x00, 0x00, 0xbb
2019-11-20 19:36:00.503 Detail,
2019-11-20 19:36:00.503 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:36:00.503 Detail, Node006,   Expected reply was received
2019-11-20 19:36:00.503 Detail, Node006,   Message transaction complete
2019-11-20 19:36:00.503 Detail,
2019-11-20 19:36:00.503 Detail, contrlr, Removing current message
2019-11-20 19:36:00.503 Detail, Notification: ControllerCommand - Completed
2019-11-20 19:36:14.150 Detail, Queuing (Controller) Add Device
2019-11-20 19:36:14.151 Info, Add Device
2019-11-20 19:36:14.151 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x10, 0x61
2019-11-20 19:36:14.151 Detail, Notification: ControllerCommand - Starting
2019-11-20 19:36:14.152 Detail,
2019-11-20 19:36:14.152 Info, contrlr, Sending (Command) message (Callback ID=0x10, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x10, 0x61
2019-11-20 19:36:14.156 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x10, 0x01, 0x00, 0x00, 0xa3
2019-11-20 19:36:14.157 Detail,
2019-11-20 19:36:14.157 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:36:14.157 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2019-11-20 19:36:14.157 Detail, Node001,   Expected callbackId was received
2019-11-20 19:36:14.158 Detail, Node001,   Expected reply was received
2019-11-20 19:36:14.158 Detail, Node001,   Message transaction complete
2019-11-20 19:36:14.158 Detail,
2019-11-20 19:36:14.158 Detail, contrlr, Removing current message
2019-11-20 19:36:14.158 Detail, Notification: ControllerCommand - Waiting
2019-11-20 19:36:14.159 Info, WriteNextMsg Controller nothing to do
2019-11-20 19:36:21.442 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2019-11-20 19:36:21.442 Detail, Node003, QueryStage_Probe
2019-11-20 19:36:21.442 Info, Node003, NoOperation::Set - Routing=true
2019-11-20 19:36:21.442 Detail,
2019-11-20 19:36:21.442 Detail, Node003, Queuing (Controller) Add Device
2019-11-20 19:36:21.442 Info,
2019-11-20 19:36:21.443 Detail, Node003, Queuing (WakeUp) Query Stage Complete (Probe)
2019-11-20 19:36:21.443 Detail, Notification: ControllerCommand - Sleeping
2019-11-20 19:36:53.811 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2019-11-20 19:36:53.811 Detail, Node003, QueryStage_Probe
2019-11-20 19:36:53.811 Info, Node003, NoOperation::Set - Routing=true
2019-11-20 19:36:53.811 Detail,
2019-11-20 19:36:53.812 Detail, Node003, Queuing (WakeUp) NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x12, 0xd3
2019-11-20 19:36:53.812 Info,
2019-11-20 19:36:53.812 Detail, Node003, Queuing (WakeUp) Query Stage Complete (Probe)
2019-11-20 19:51:45.891 Info, mgr,     Manager::WriteConfig completed for driver with home ID of 0xf1913055
2019-11-20 19:51:46.894 Info, mgr,     Driver for controller /dev/serial/by-id/usb-0658_0200-if00 pending removal
2019-11-20 19:51:46.894 Detail, Notification: DriverRemoved
2019-11-20 19:51:46.895 Always, ***************************************************************************
2019-11-20 19:51:46.895 Always, *********************  Cumulative Network Statistics  *********************
2019-11-20 19:51:46.895 Always, *** General
2019-11-20 19:51:46.895 Always, Driver run time: . .  . 0 days, 0 hours, 28 minutes
2019-11-20 19:51:46.895 Always, Frames processed: . . . . . . . . . . . . . . . . . . . . 16
2019-11-20 19:51:46.895 Always, Total messages successfully received: . . . . . . . . . . 16
2019-11-20 19:51:46.895 Always, Total Messages successfully sent: . . . . . . . . . . . . 16
2019-11-20 19:51:46.895 Always, ACKs received from controller:  . . . . . . . . . . . . . 16
2019-11-20 19:51:46.895 Always, *** Errors
2019-11-20 19:51:46.895 Always, Unsolicited messages received while waiting for ACK:  . . 0
2019-11-20 19:51:46.895 Always, Reads aborted due to timeouts:  . . . . . . . . . . . . . 0
2019-11-20 19:51:46.895 Always, Bad checksum errors:  . . . . . . . . . . . . . . . . . . 0
2019-11-20 19:51:46.895 Always, CANs received from controller:  . . . . . . . . . . . . . 0
2019-11-20 19:51:46.895 Always, NAKs received from controller:  . . . . . . . . . . . . . 0
2019-11-20 19:51:46.895 Always, Out of frame data flow errors:  . . . . . . . . . . . . . 0
2019-11-20 19:51:46.896 Always, Messages retransmitted: . . . . . . . . . . . . . . . . . 0
2019-11-20 19:51:46.896 Always, Messages dropped and not delivered: . . . . . . . . . . . 0
2019-11-20 19:51:46.896 Always, ***************************************************************************
2019-11-20 19:51:48.901 Info, mgr,     Driver for controller /dev/serial/by-id/usb-0658_0200-if00 removed
2019-11-20 19:51:49.909 Error, mgr,     Manager::GetDriver failed - Home ID 0xf1913055 is unknown
2019-11-20 19:51:49.909 Warning, Exception: Manager.cpp:373 - 100 - Invalid HomeId passed to GetDriver
2019-11-20 19:51:49.909 Info, mgr,     GetSendQueueCount() failed - _homeId -242143147 not found
2019-11-20 19:51:57.056 Info, Setting Up Provided Network Key for Secure Communications
2019-11-20 19:51:57.056 Info, mgr,     Added driver for controller /dev/serial/by-id/usb-0658_0200-if00
2019-11-20 19:51:57.056 Info,   Opening controller /dev/serial/by-id/usb-0658_0200-if00
2019-11-20 19:51:57.057 Info, Trying to open serial port /dev/serial/by-id/usb-0658_0200-if00 (attempt 1)
2019-11-20 19:51:57.058 Info, Serial port /dev/serial/by-id/usb-0658_0200-if00 opened (attempt 1)
2019-11-20 19:51:57.058 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-11-20 19:51:57.058 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-11-20 19:51:57.059 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-11-20 19:51:57.059 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-11-20 19:51:57.059 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-11-20 19:51:57.059 Detail,
2019-11-20 19:51:57.059 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-11-20 19:51:57.065 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x35, 0x34, 0x00, 0x01, 0x93
2019-11-20 19:51:57.065 Detail,
2019-11-20 19:51:57.065 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2019-11-20 19:51:57.065 Info, contrlr,     Static Controller library, version Z-Wave 4.54
2019-11-20 19:51:57.065 Detail, Node045,   Expected reply was received
2019-11-20 19:51:57.065 Detail, Node045,   Message transaction complete
2019-11-20 19:51:57.065 Detail,
2019-11-20 19:51:57.065 Detail, contrlr, Removing current message
2019-11-20 19:51:57.065 Detail,
2019-11-20 19:51:57.065 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-11-20 19:51:57.068 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xf1, 0x91, 0x30, 0x55, 0x01, 0xd2
2019-11-20 19:51:57.068 Detail,
2019-11-20 19:51:57.068 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xf1913055.  Our node ID = 1
2019-11-20 19:51:57.068 Detail, Node145,   Expected reply was received
2019-11-20 19:51:57.068 Detail, Node145,   Message transaction complete
2019-11-20 19:51:57.068 Detail,
2019-11-20 19:51:57.068 Detail, contrlr, Removing current message
2019-11-20 19:51:57.068 Detail,
2019-11-20 19:51:57.068 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-11-20 19:51:57.070 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2019-11-20 19:51:57.071 Detail,
2019-11-20 19:51:57.071 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-11-20 19:51:57.071 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2019-11-20 19:51:57.071 Info, contrlr,     The PC controller is a primary controller.
2019-11-20 19:51:57.071 Detail, Node247,   Expected reply was received
2019-11-20 19:51:57.071 Detail, Node247,   Message transaction complete
2019-11-20 19:51:57.071 Detail,
2019-11-20 19:51:57.071 Detail, contrlr, Removing current message
2019-11-20 19:51:57.071 Detail,
2019-11-20 19:51:57.071 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-11-20 19:51:57.075 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x01, 0x00, 0x86, 0x00, 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, 0xfa
2019-11-20 19:51:57.075 Detail,
2019-11-20 19:51:57.075 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-11-20 19:51:57.075 Info, contrlr,     Serial API Version:   1.1
2019-11-20 19:51:57.075 Info, contrlr,     Manufacturer ID:      0x0086
2019-11-20 19:51:57.075 Info, contrlr,     Product Type:         0x0001
2019-11-20 19:51:57.075 Info, contrlr,     Product ID:           0x005a
2019-11-20 19:51:57.075 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-11-20 19:51:57.075 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-11-20 19:51:57.075 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-11-20 19:51:57.075 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-11-20 19:51:57.075 Detail, Node001,   Expected reply was received
2019-11-20 19:51:57.075 Detail, Node001,   Message transaction complete
2019-11-20 19:51:57.075 Detail,
2019-11-20 19:51:57.075 Detail, contrlr, Removing current message
2019-11-20 19:51:57.075 Detail,
2019-11-20 19:51:57.075 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-11-20 19:51:57.077 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2019-11-20 19:51:57.077 Detail,
2019-11-20 19:51:57.077 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2019-11-20 19:51:57.077 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2019-11-20 19:51:57.077 Detail, Node172,   Expected reply was received
2019-11-20 19:51:57.078 Detail, Node172,   Message transaction complete
2019-11-20 19:51:57.078 Detail,
2019-11-20 19:51:57.078 Detail, contrlr, Removing current message
2019-11-20 19:51:57.078 Detail,
2019-11-20 19:51:57.078 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-11-20 19:51:57.134 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x36, 0xcf, 0x81, 0x7f, 0xa5, 0x49, 0x0e, 0x45, 0x32, 0xa1, 0xcd, 0x37, 0x79, 0xbf, 0x7d, 0x46, 0x23, 0x04, 0xc7, 0x35, 0xd6, 0x4e, 0x23, 0xef, 0xd1, 0xd0, 0xf5, 0x29, 0x70, 0xaa, 0x69, 0x5f, 0x62
2019-11-20 19:51:57.134 Detail,
2019-11-20 19:51:57.134 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-11-20 19:51:57.134 Detail, Node032,   Expected reply was received
2019-11-20 19:51:57.134 Detail, Node032,   Message transaction complete
2019-11-20 19:51:57.134 Detail,
2019-11-20 19:51:57.134 Detail, contrlr, Removing current message
2019-11-20 19:51:57.134 Detail,
2019-11-20 19:51:57.134 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-11-20 19:51:57.239 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x05, 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, 0xc1
2019-11-20 19:51:57.239 Detail,
2019-11-20 19:51:57.239 Info, mgr,     Driver with Home ID of 0xf1913055 is now ready.
...
2019-11-20 19:51:57.278 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2019-11-20 19:51:57.278 Detail, Node015,   Expected reply was received
2019-11-20 19:51:57.278 Detail, Node015,   Message transaction complete
2019-11-20 19:51:57.278 Detail,
2019-11-20 19:51:57.278 Detail, contrlr, Removing current message
2019-11-20 19:51:57.278 Detail,
2019-11-20 19:51:57.278 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
2019-11-20 19:51:57.280 Detail, contrlr, Removing current message
2019-11-20 19:51:57.280 Detail,
2019-11-20 19:51:57.280 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2019-11-20 19:51:57.285 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x04, 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, 0x5a
2019-11-20 19:51:57.285 Detail,
2019-11-20 19:51:57.285 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-11-20 19:51:57.285 Info, Node001,     Neighbors of this node are:
2019-11-20 19:51:57.285 Info, Node001,     Node 3
2019-11-20 19:51:57.285 Detail,   Expected reply was received
2019-11-20 19:51:57.285 Detail,   Message transaction complete
2019-11-20 19:51:57.285 Detail,
2019-11-20 19:51:57.285 Detail, Node001, Removing current message
2019-11-20 19:51:57.285 Detail,
2019-11-20 19:51:57.285 Info, Node003, Sending (NoOp) message (Callback ID=0x13, Expected Reply=0x13) - NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x13, 0xd2
2019-11-20 19:51:57.297 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-11-20 19:51:57.297 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2019-11-20 19:52:01.394 Detail, Node003,   Received: 0x01, 0x07, 0x00, 0x13, 0x13, 0x01, 0x01, 0x9b, 0x63
2019-11-20 19:52:01.395 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
2019-11-20 19:52:01.395 Info, Node003, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-11-20 19:52:01.395 Info, Node003,   Node 3 has been marked as asleep
2019-11-20 19:52:01.395 Info, Node003, Node not responding - moving QueryStageComplete command to Wake-Up queue
2019-11-20 19:52:01.395 Detail, Node003, Notification: Notification - NoOperation
2019-11-20 19:52:01.399 Detail, Node003, Notification: Notification - Node Asleep
2019-11-20 19:52:01.405 Detail, Node001, Query Stage Complete (Neighbors)
2019-11-20 19:52:01.405 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2019-11-20 19:52:01.405 Detail, Node001, QueryStage_Session
2019-11-20 19:52:01.405 Detail, Node001, QueryStage_Dynamic
2019-11-20 19:52:01.405 Detail, Node001, QueryStage_Configuration
2019-11-20 19:52:01.405 Detail, Node001, QueryStage_Complete
2019-11-20 19:52:01.405 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-11-20 19:52:01.405 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=1
2019-11-20 19:52:01.405 Info,          Node query processing complete except for sleeping nodes.
2019-11-20 19:52:01.406 Detail, Node001, Notification: NodeQueriesComplete
2019-11-20 19:52:01.408 Detail, contrlr, Notification: AwakeNodesQueried
2019-11-20 19:52:23.824 Detail, Queuing (Controller) Add Device
2019-11-20 19:52:23.824 Info, Add Device
2019-11-20 19:52:23.824 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65
2019-11-20 19:52:23.825 Detail, Notification: ControllerCommand - Starting
2019-11-20 19:52:23.826 Detail,
2019-11-20 19:52:23.826 Info, contrlr, Sending (Command) message (Callback ID=0x14, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65
2019-11-20 19:52:23.828 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x07, 0x00, 0x00, 0xa1
2019-11-20 19:52:23.829 Detail,
2019-11-20 19:52:23.829 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:52:23.829 Info, contrlr, ADD_NODE_STATUS_FAILED
2019-11-20 19:52:23.829 Detail, contrlr, Removing current message
2019-11-20 19:52:23.829 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:52:23.829 Detail, Notification: ControllerCommand - Failed
2019-11-20 19:52:23.831 Detail,
2019-11-20 19:52:23.831 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:52:23.883 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x06, 0x00, 0x00, 0xa0
2019-11-20 19:52:23.883 Detail,
2019-11-20 19:52:23.883 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:52:23.883 Detail, Node006,   Expected reply was received
2019-11-20 19:52:23.883 Detail, Node006,   Message transaction complete
2019-11-20 19:52:23.883 Detail,
2019-11-20 19:52:23.883 Detail, contrlr, Removing current message
2019-11-20 19:52:23.883 Detail, Notification: ControllerCommand - Completed
2019-11-20 19:52:37.916 Detail, Queuing (Controller) Add Device
2019-11-20 19:52:37.917 Info, Add Device
2019-11-20 19:52:37.917 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x15, 0x64
2019-11-20 19:52:37.917 Detail, Notification: ControllerCommand - Starting
2019-11-20 19:52:37.918 Detail,
2019-11-20 19:52:37.918 Info, contrlr, Sending (Command) message (Callback ID=0x15, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x15, 0x64
2019-11-20 19:52:37.922 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x15, 0x01, 0x00, 0x00, 0xa6
2019-11-20 19:52:37.922 Detail,
2019-11-20 19:52:37.922 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:52:37.922 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2019-11-20 19:52:37.922 Detail, Node001,   Expected callbackId was received
2019-11-20 19:52:37.922 Detail, Node001,   Expected reply was received
2019-11-20 19:52:37.922 Detail, Node001,   Message transaction complete
2019-11-20 19:52:37.923 Detail,
2019-11-20 19:52:37.923 Detail, contrlr, Removing current message
2019-11-20 19:52:37.923 Detail, Notification: ControllerCommand - Waiting
2019-11-20 19:52:37.924 Info, WriteNextMsg Controller nothing to do
2019-11-20 19:53:00.439 Info, Cancel Add Node
2019-11-20 19:53:00.439 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:53:00.439 Detail, Notification: ControllerCommand - Canceled
2019-11-20 19:53:00.440 Detail,
2019-11-20 19:53:00.440 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2019-11-20 19:53:00.529 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x15, 0x06, 0x00, 0x00, 0xa1
2019-11-20 19:53:00.530 Detail,
2019-11-20 19:53:00.530 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:53:00.530 Info, contrlr, ADD_NODE_STATUS_DONE
2019-11-20 19:53:00.530 Detail, Node006,   Expected reply was received
2019-11-20 19:53:00.530 Detail, Node006,   Message transaction complete
2019-11-20 19:53:00.530 Detail,
2019-11-20 19:53:00.530 Detail, contrlr, Removing current message
2019-11-20 19:53:00.530 Detail, Notification: ControllerCommand - Completed
2019-11-20 19:53:02.041 Detail, Queuing (Controller) Add Device
2019-11-20 19:53:02.041 Info, Add Device
2019-11-20 19:53:02.041 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67
2019-11-20 19:53:02.042 Detail, Notification: ControllerCommand - Starting
2019-11-20 19:53:02.045 Detail,
2019-11-20 19:53:02.045 Info, contrlr, Sending (Command) message (Callback ID=0x16, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67
2019-11-20 19:53:02.049 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x16, 0x01, 0x00, 0x00, 0xa5
2019-11-20 19:53:02.049 Detail,
2019-11-20 19:53:02.049 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2019-11-20 19:53:02.049 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2019-11-20 19:53:02.049 Detail, Node001,   Expected callbackId was received
2019-11-20 19:53:02.049 Detail, Node001,   Expected reply was received
2019-11-20 19:53:02.049 Detail, Node001,   Message transaction complete
2019-11-20 19:53:02.049 Detail,
2019-11-20 19:53:02.049 Detail, contrlr, Removing current message
2019-11-20 19:53:02.050 Detail, Notification: ControllerCommand - Waiting
2019-11-20 19:53:02.052 Info, WriteNextMsg Controller nothing to do

firstof9 · November 20, 2019, 9:39pm

What device are you trying to add?

The add device command is being executed by the stick but nothing’s requesting an add so the request times out.

stivoberlin · November 20, 2019, 9:41pm

hello Fibaro “The button”, I tried to reset it but won’t show up

firstof9 · November 20, 2019, 9:43pm

After clicking add node did you click your button 6 times per the manual?

stivoberlin · November 20, 2019, 9:44pm

yep. i tried many times to make a reset (5 tap, then 5 sec press) then pair again with 6 taps

stivoberlin · November 20, 2019, 9:45pm

i tried to pair also a “fibaro motion sensor” … impossible it doesn’t show up.

firstof9 · November 20, 2019, 9:47pm

Are you executing the pairing while sitting next to the Pi with the zwave stick?

stivoberlin · November 20, 2019, 9:49pm

i tried to pair a wallmote quad, it pairs but then it doesn’t react to my automation script :

- id: '1574281822150'
  alias: kitchen toggle
  description: ''
  trigger:
  - event_data:
      entity_id: zwave.aeon_labs_zw130_wallmote_quad
      scene_data: 0
      scene_id: 1
    event_type: zwave.scene_activated
    platform: event
  condition: []
  action:
  - device_id: b766f97e7f9d4cb9b3ce61e3414cc5be
    domain: light
    entity_id: light.kitchen
    type: toggle

altough i have this in the log :

2019-11-20 22:47:51.984 Info, Node004, Received Central Scene set from node 4: scene id=1 in 0 seconds. Sending event notification.
2019-11-20 22:47:51.984 Warning, Node004, No ValueID created for Scene 1

i have plenty of other devices but no luck with the first 3 i tried to use…

stivoberlin · November 20, 2019, 9:51pm

the only things that is now working are my philipps hue lights… nothing with zwave is working altough it seems there is activity with zwave

firstof9 · November 20, 2019, 9:56pm

This thread has info about the scene stuff I’d double check there for more info about that.

As for your other battery operated devices, you have to pair them near the stick, basically on the desk next to it. Try doing the remove node process first, then do the add process. Also note, if you have not mains power nodes, your battery operated devices are going to have a bad time in a large home.

stivoberlin · November 20, 2019, 10:42pm

thanks ! you are the BEST ! the trick for the scene stuff has worked. I have at least one switch that is working !

Coolie1101 · November 22, 2019, 10:42pm

Hey @stivoberlin, how were you able to resolve this issue?, I’m experiencing the same issue, where automations are not being triggered by the Wallmote Quad.

stivoberlin · February 22, 2021, 10:55pm

It was working for some time but no more…