Fresh installation only dead nodes after include (RPi2 + Zwave.me USB)

zamb · October 29, 2017, 9:47pm

Running on RPi2
Zwave.me UZB USB stick

Devices tried to include:
Aeon Labs - Nano Dimmer
Qubino - Flush 2 Relays

I have installed: RASPBIAN STRETCH LITE

Followed the guide here: docs/installation/virtualenv/
Setup autostart according to this guide: /docs/autostart/systemd/
Adding /dev/zwave according to this guide: https://partofthething.com/thoughts/home-automation-with-z-wave-home-assistant-aeon-multisensor-hue-lights-and-a-raspberry-pi-2/

Added to configuration.yaml
zwave:
usb_path: /dev/zwave
network_key: “0x01, 0x02…16 bytes!”

I added the same network_key to options.xml:

<Option name="NetworkKey" value="0x01, 0x02...16 bytes!"

Can go to the HASS webs-application, and go to:
Configuration - Z-Wave Network Management.

I tail the OZW.log and send te command “Add node secure”.
Then hit the z-wave device include button or the correct sequence.
The log indicates inclusion.
Have the RPi 30cm from the device and wait 5 minutes (i got advice to do so…), then I restart HASS.
The device are included but DEAD.
I try to heal network, and exclude (works fine to exclude) and then re-include it again… Always the same issue. Nodes are dead.
I have no nodes successfully included.

I have tried this several times now and start to get a little bit frustrated as I cannot see what Im doing wrong…

I have done “Soft Reset” from Configuration - Z-Wave Network Management.
Is there a way to do a hard reset of the device, to start over from scratch?

I have reinstalled the RPi two times (thought I have missed something in the initial first setup…).

Where shall I look and try to find a solution? Advice me please…

This is from my OZW_Log.txt, I

2017-10-29 22:19:11.819 Always, OpenZwave Version 1.4.2586 Starting Up
2017-10-29 22:19:13.129 Info, Setting Up Provided Network Key for Secure Communications
2017-10-29 22:19:13.137 Info, mgr,     Added driver for controller /dev/zwave
2017-10-29 22:19:13.137 Info,   Opening controller /dev/zwave
2017-10-29 22:19:13.138 Info, Trying to open serial port /dev/zwave (attempt 1)
2017-10-29 22:19:13.139 Info, Serial port /dev/zwave opened (attempt 1)
2017-10-29 22:19:13.140 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2017-10-29 22:19:13.140 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2017-10-29 22:19:13.149 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2017-10-29 22:19:13.149 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2017-10-29 22:19:13.149 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2017-10-29 22:19:13.150 Detail, 
2017-10-29 22:19:13.150 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2017-10-29 22:19:13.152 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97
2017-10-29 22:19:13.153 Detail, 
2017-10-29 22:19:13.153 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2017-10-29 22:19:13.153 Info, contrlr,     Static Controller library, version Z-Wave 4.05
2017-10-29 22:19:13.153 Detail, Node045,   Expected reply was received
2017-10-29 22:19:13.153 Detail, Node045,   Message transaction complete
2017-10-29 22:19:13.153 Detail, 
2017-10-29 22:19:13.153 Detail, contrlr, Removing current message
2017-10-29 22:19:13.153 Detail, 
2017-10-29 22:19:13.153 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2017-10-29 22:19:13.154 Detail, Unsolicited message received while waiting for ACK.
2017-10-29 22:19:13.154 Detail, contrlr,   Received: 0x01, 0x09, 0x00, 0x0a, 0x05, 0x00, 0x01, 0x02, 0x01, 0x00, 0xfb
2017-10-29 22:19:13.154 Detail, 
2017-10-29 22:19:13.154 Info, **TODO: handle request for 0x0a** Please report this message.
2017-10-29 22:19:13.155 Detail, contrlr, CAN received...triggering resend
2017-10-29 22:19:13.155 Detail, 
2017-10-29 22:19:13.155 Info, contrlr, Sending (Command) message (Attempt 2, Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2017-10-29 22:19:13.158 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0x55, 0x55, 0x55, 0x55, 0x01, 0xd7
2017-10-29 22:19:13.158 Detail, 
2017-10-29 22:19:13.159 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0x55555555.  Our node ID = 1
2017-10-29 22:19:13.162 Detail, Node085,   Expected reply was received
2017-10-29 22:19:13.163 Detail, Node085,   Message transaction complete
2017-10-29 22:19:13.163 Detail, 
2017-10-29 22:19:13.163 Detail, contrlr, Removing current message
2017-10-29 22:19:13.163 Detail, 
2017-10-29 22:19:13.163 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2017-10-29 22:19:13.165 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2017-10-29 22:19:13.170 Detail, 
2017-10-29 22:19:13.170 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2017-10-29 22:19:13.170 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
2017-10-29 22:19:13.170 Info, contrlr,     The PC controller is a primary controller.
2017-10-29 22:19:13.170 Detail, Node247,   Expected reply was received
2017-10-29 22:19:13.170 Detail, Node247,   Message transaction complete
2017-10-29 22:19:13.170 Detail, 
2017-10-29 22:19:13.170 Detail, contrlr, Removing current message
2017-10-29 22:19:13.170 Detail, 
2017-10-29 22:19:13.170 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2017-10-29 22:19:13.175 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x05, 0x05, 0x01, 0x15, 0x04, 0x00, 0x00, 0x01, 0xfe, 0x83, 0xff, 0x88, 0xcf, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x80, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x40, 0x1a, 0x00, 0x0e
2017-10-29 22:19:13.175 Detail, 
2017-10-29 22:19:13.175 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2017-10-29 22:19:13.175 Info, contrlr,     Serial API Version:   5.5
2017-10-29 22:19:13.175 Info, contrlr,     Manufacturer ID:      0x0115
2017-10-29 22:19:13.175 Info, contrlr,     Product Type:         0x0400
2017-10-29 22:19:13.175 Info, contrlr,     Product ID:           0x0001
2017-10-29 22:19:13.175 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2017-10-29 22:19:13.175 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2017-10-29 22:19:13.175 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2017-10-29 22:19:13.176 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2017-10-29 22:19:13.176 Detail, Node005,   Expected reply was received
2017-10-29 22:19:13.176 Detail, Node005,   Message transaction complete
2017-10-29 22:19:13.176 Detail, 
2017-10-29 22:19:13.176 Detail, contrlr, Removing current message
2017-10-29 22:19:13.176 Detail, 
2017-10-29 22:19:13.176 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2017-10-29 22:19:13.179 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2017-10-29 22:19:13.179 Detail, 
2017-10-29 22:19:13.179 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
2017-10-29 22:19:13.179 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2017-10-29 22:19:13.179 Detail, Node172,   Expected reply was received
2017-10-29 22:19:13.179 Detail, Node172,   Message transaction complete
2017-10-29 22:19:13.179 Detail, 
2017-10-29 22:19:13.179 Detail, contrlr, Removing current message
2017-10-29 22:19:13.179 Detail, 
2017-10-29 22:19:13.179 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2017-10-29 22:19:13.235 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x1a, 0x24, 0x86, 0xb5, 0x0f, 0x3a, 0x18, 0x9e, 0xd7, 0x65, 0x43, 0xea, 0xa4, 0x22, 0xc9, 0xd5, 0x8c, 0x14, 0x47, 0x91, 0x55, 0x39, 0x18, 0x1b, 0xdd, 0xeb, 0x7e, 0xb3, 0xa7, 0x79, 0xec, 0xfa, 0xcb
2017-10-29 22:19:13.235 Detail, 
2017-10-29 22:19:13.235 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2017-10-29 22:19:13.235 Detail, Node032,   Expected reply was received
2017-10-29 22:19:13.236 Detail, Node032,   Message transaction complete
2017-10-29 22:19:13.236 Detail, 
2017-10-29 22:19:13.236 Detail, contrlr, Removing current message
2017-10-29 22:19:13.236 Detail, 
2017-10-29 22:19:13.236 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2017-10-29 22:19:13.334 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x09, 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, 0xcd
2017-10-29 22:19:13.335 Detail, 
2017-10-29 22:19:13.335 Info, mgr,     Driver with Home ID of 0x55555555 is now ready.
2017-10-29 22:19:13.335 Info, 
2017-10-29 22:19:13.346 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2017-10-29 22:19:13.346 Info, contrlr,     Node 001 - Known
2017-10-29 22:19:13.346 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2017-10-29 22:19:13.346 Detail, Node001, QueryStage_CacheLoad
2017-10-29 22:19:13.349 Info, Node001, Node Identity Codes: 0115:0400:0001
2017-10-29 22:19:13.349 Detail, Node001, QueryStage_Associations
2017-10-29 22:19:13.349 Detail, Node001, QueryStage_Neighbors
2017-10-29 22:19:13.349 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2017-10-29 22:19:13.349 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2017-10-29 22:19:13.349 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2017-10-29 22:19:13.349 Info, contrlr,     Node 004 - Known
2017-10-29 22:19:13.349 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2017-10-29 22:19:13.350 Detail, Node004, QueryStage_CacheLoad
2017-10-29 22:19:13.350 Info, Node004, Node Identity Codes: 0000:0000:0000
2017-10-29 22:19:13.350 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 22:19:13.350 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcc
2017-10-29 22:19:13.350 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2017-10-29 22:19:13.350 Detail,   Expected reply was received
2017-10-29 22:19:13.350 Detail,   Message transaction complete
2017-10-29 22:19:13.350 Detail, 
2017-10-29 22:19:13.350 Detail, contrlr, Removing current message
2017-10-29 22:19:13.350 Detail, Node001, Notification: DriverReady
2017-10-29 22:19:13.354 Detail, Node001, Notification: NodeAdded
2017-10-29 22:19:13.367 Detail, Node001, Notification: NodeProtocolInfo
2017-10-29 22:19:13.370 Detail, Node001, Notification: EssentialNodeQueriesComplete
2017-10-29 22:19:13.373 Detail, Node001, Notification: ValueAdded
2017-10-29 22:19:13.378 Detail, Node001, Notification: NodeNaming
2017-10-29 22:19:13.380 Detail, Node004, Notification: NodeAdded
2017-10-29 22:19:13.394 Detail, Node004, Notification: NodeProtocolInfo
2017-10-29 22:19:13.396 Detail, Node004, Notification: EssentialNodeQueriesComplete
2017-10-29 22:19:13.397 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.413 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.417 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.523 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.536 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.543 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.550 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.561 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.566 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.569 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.583 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.594 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.596 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.608 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.614 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.618 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.620 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.627 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.634 Detail, Node004, Notification: ValueAdded
2017-10-29 22:19:13.638 Detail, 
2017-10-29 22:19:13.639 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2017-10-29 22:19:13.641 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x96, 0x0f, 0x64
2017-10-29 22:19:13.644 Detail, 
2017-10-29 22:19:13.644 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2017-10-29 22:19:13.644 Detail, Node015,   Expected reply was received
2017-10-29 22:19:13.644 Detail, Node015,   Message transaction complete
2017-10-29 22:19:13.644 Detail, 
2017-10-29 22:19:13.644 Detail, contrlr, Removing current message
2017-10-29 22:19:13.644 Detail, 
2017-10-29 22:19:13.644 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
2017-10-29 22:19:13.647 Detail, contrlr, Removing current message
2017-10-29 22:19:13.647 Detail, 
2017-10-29 22:19:13.648 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
2017-10-29 22:19:13.652 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5e
2017-10-29 22:19:13.653 Detail, 
2017-10-29 22:19:13.653 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2017-10-29 22:19:13.653 Info, Node001,     Neighbors of this node are:
2017-10-29 22:19:13.653 Info, Node001,  (none reported)
2017-10-29 22:19:13.653 Detail,   Expected reply was received
2017-10-29 22:19:13.653 Detail,   Message transaction complete
2017-10-29 22:19:13.653 Detail, 
2017-10-29 22:19:13.653 Detail, Node001, Removing current message
2017-10-29 22:19:13.653 Detail, 
2017-10-29 22:19:13.653 Info, Node004, Sending (NoOp) message (Callback ID=0x0a, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcc
2017-10-29 22:19:13.665 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 22:19:13.666 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 22:19:17.793 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0a, 0x01, 0x01, 0x9e, 0x7f
2017-10-29 22:19:17.794 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2017-10-29 22:19:17.794 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 22:19:17.794 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 22:19:17.794 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2017-10-29 22:19:17.794 Detail, Node001,   Expected callbackId was received
2017-10-29 22:19:17.794 Detail, Node001,   Expected reply was received
2017-10-29 22:19:17.794 Detail, Node001,   Message transaction complete
2017-10-29 22:19:17.795 Detail, 
2017-10-29 22:19:17.795 Detail, Node004, Removing current message
2017-10-29 22:19:17.795 Detail, Node004, Notification: Notification - NoOperation
2017-10-29 22:19:17.799 Detail, Node001, Query Stage Complete (Neighbors)
2017-10-29 22:19:17.799 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2017-10-29 22:19:17.799 Detail, Node001, QueryStage_Session
2017-10-29 22:19:17.799 Detail, Node001, QueryStage_Dynamic
2017-10-29 22:19:17.799 Detail, Node001, QueryStage_Configuration
2017-10-29 22:19:17.800 Detail, Node001, QueryStage_Complete
2017-10-29 22:19:17.800 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2017-10-29 22:19:17.800 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2017-10-29 22:19:17.800 Detail, Node001, Notification: NodeQueriesComplete
2017-10-29 22:19:17.803 Detail, Node004, Query Stage Complete (CacheLoad)
2017-10-29 22:19:17.803 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=1
2017-10-29 22:19:17.804 Detail, Node004, QueryStage_CacheLoad
2017-10-29 22:19:17.804 Info, Node004, Node Identity Codes: 0000:0000:0000
2017-10-29 22:19:17.804 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 22:19:17.804 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2017-10-29 22:19:17.804 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2017-10-29 22:19:17.804 Detail, 
2017-10-29 22:19:17.805 Info, Node004, Sending (NoOp) message (Callback ID=0x0b, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2017-10-29 22:19:17.817 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 22:19:17.818 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 22:19:28.440 Error, Node004, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-10-29 22:19:28.440 Detail, Node004, Removing current message
2017-10-29 22:19:28.441 Detail, Node004, Notification: Notification - TimeOut
2017-10-29 22:19:28.445 Detail, Node004, Query Stage Complete (CacheLoad)
2017-10-29 22:19:28.445 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2017-10-29 22:19:28.445 Detail, Node004, QueryStage_Associations
2017-10-29 22:19:28.445 Info, Node004, Number of association groups reported for node 4 is 0.
2017-10-29 22:19:28.445 Info, Node004, Get MultiChannelAssociation for group 1 of node 4
2017-10-29 22:19:28.445 Detail, Node004, Queuing (Send) MultiChannelAssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x8e, 0x02, 0x01, 0x25, 0x0c, 0x45
2017-10-29 22:19:28.446 Detail, Node004, Queuing (Query) Query Stage Complete (Associations)
2017-10-29 22:19:28.446 Detail, 
2017-10-29 22:19:28.446 Info, Node004, Sending (Send) message (Callback ID=0x0c, Expected Reply=0x04) - MultiChannelAssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x8e, 0x02, 0x01, 0x25, 0x0c, 0x45
2017-10-29 22:19:28.459 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 22:19:28.459 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 22:19:30.957 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0c, 0x01, 0x00, 0xfb, 0x1d
2017-10-29 22:19:30.957 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2017-10-29 22:19:30.957 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 22:19:30.957 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 22:19:30.957 Detail, Node001,   Expected callbackId was received
2017-10-29 22:19:32.587 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0c, 0x01, 0x01, 0x9e, 0x79
2017-10-29 22:19:32.587 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x00)
2017-10-29 22:19:32.587 Warning, Node004, WARNING: Unexpected Callback ID received
2017-10-29 22:19:38.447 Error, Node004, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-10-29 22:19:38.447 Detail, Node004, Removing current message
2017-10-29 22:19:38.447 Detail, Node004, Notification: Notification - TimeOut
2017-10-29 22:19:38.452 Detail, Node004, Query Stage Complete (Associations)
2017-10-29 22:19:38.452 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2017-10-29 22:19:38.452 Detail, Node004, QueryStage_Neighbors
2017-10-29 22:19:38.452 Detail, Requesting routing info (neighbor list) for Node 4
2017-10-29 22:19:38.452 Detail, Node004, Queuing (Command) Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2017-10-29 22:19:38.453 Detail, Node004, Queuing (Query) Query Stage Complete (Neighbors)
2017-10-29 22:19:38.453 Detail, 
2017-10-29 22:19:38.453 Info, Node004, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2017-10-29 22:19:38.458 Detail, Node004,   Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5e
2017-10-29 22:19:38.458 Detail, 
2017-10-29 22:19:38.458 Info, Node004, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2017-10-29 22:19:38.459 Info, Node004,     Neighbors of this node are:
2017-10-29 22:19:38.459 Info, Node004,  (none reported)
2017-10-29 22:19:38.459 Detail,   Expected reply was received
2017-10-29 22:19:38.459 Detail,   Message transaction complete
2017-10-29 22:19:38.459 Detail, 
2017-10-29 22:19:38.459 Detail, Node004, Removing current message
2017-10-29 22:19:38.459 Detail, Node004, Query Stage Complete (Neighbors)
2017-10-29 22:19:38.460 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2017-10-29 22:19:38.460 Detail, Node004, QueryStage_Session
2017-10-29 22:19:38.460 Detail, Node004, Queuing (Query) SwitchAllCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x27, 0x02, 0x25, 0x0d, 0xee
2017-10-29 22:19:38.460 Detail, Node004, Queuing (Query) PowerlevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x73, 0x02, 0x25, 0x0e, 0xb9
2017-10-29 22:19:38.460 Detail, Node004, Queuing (Query) Query Stage Complete (Session)
2017-10-29 22:19:38.461 Detail, 
2017-10-29 22:19:38.461 Info, Node004, Sending (Query) message (Callback ID=0x0d, Expected Reply=0x04) - SwitchAllCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x27, 0x02, 0x25, 0x0d, 0xee
2017-10-29 22:19:38.473 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 22:19:38.473 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 22:19:42.571 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0d, 0x01, 0x01, 0x9a, 0x7c
2017-10-29 22:19:42.571 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2017-10-29 22:19:42.572 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 22:19:42.572 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 22:19:42.572 Error, Node004, ERROR: node presumed dead
2017-10-29 22:19:42.572 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2017-10-29 22:19:42.572 Warning, CheckCompletedNodeQueries all=1, deadFound=1 sleepingOnly=1
2017-10-29 22:19:42.572 Info,          Node query processing complete except for dead nodes.
2017-10-29 22:19:42.572 Detail, Node001,   Expected callbackId was received
2017-10-29 22:19:42.573 Detail, contrlr, Notification: AllNodesQueriedSomeDead
2017-10-29 22:19:42.573 Detail, Node004, Notification: Notification - Node Dead
2017-10-29 22:19:48.462 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:19:48.462 Detail, Node004, Removing current message
2017-10-29 22:19:48.463 Detail, Node004, Notification: Notification - TimeOut
2017-10-29 22:19:48.467 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:19:48.467 Detail, Node004, Removing current message
2017-10-29 22:19:48.467 Detail, Node004, Query Stage Complete (Session)
2017-10-29 22:19:48.467 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=0
2017-10-29 22:30:40.278 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2017-10-29 22:30:40.278 Info, Node004, SwitchBinary::Set - Setting node 4 to On
2017-10-29 22:30:40.278 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0xff, 0x25, 0x0f, 0x10
2017-10-29 22:30:40.279 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x10, 0xf1
2017-10-29 22:30:40.279 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:30:40.279 Detail, Node004, Removing current message
2017-10-29 22:30:40.279 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:30:40.279 Detail, Node004, Removing current message
2017-10-29 22:30:42.619 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2017-10-29 22:30:42.619 Info, Node004, SwitchBinary::Set - Setting node 4 to On
2017-10-29 22:30:42.619 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0xff, 0x25, 0x11, 0x0e
2017-10-29 22:30:42.620 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x12, 0xf3
2017-10-29 22:30:42.620 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:30:42.620 Detail, Node004, Removing current message
2017-10-29 22:30:42.620 Error, Node004, ERROR: Dropping command because node is presumed dead
2017-10-29 22:30:42.620 Detail, Node004, Removing current message
2017-10-29 22:30:56.000 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 22:30:56.000 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x13, 0xd5
2017-10-29 22:30:56.001 Detail, 
2017-10-29 22:30:56.001 Info, Node004, Sending (NoOp) message (Callback ID=0x13, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x13, 0xd5
2017-10-29 22:30:56.014 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 22:30:56.014 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 22:31:00.112 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x13, 0x01, 0x01, 0x9a, 0x62
2017-10-29 22:31:00.112 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
2017-10-29 22:31:00.112 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 22:31:00.112 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 22:31:00.112 Error, Node004, ERROR: node presumed dead
2017-10-29 22:31:00.113 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2017-10-29 22:31:00.113 Detail, Node001,   Expected callbackId was received
2017-10-29 22:31:00.113 Detail, Node001,   Expected reply was received
2017-10-29 22:31:00.113 Detail, Node001,   Message transaction complete
2017-10-29 22:31:00.113 Detail, 
2017-10-29 22:31:00.113 Detail, Node004, Removing current message
2017-10-29 22:31:00.113 Detail, Node004, Notification: Notification - NoOperation
2017-10-29 22:31:00.117 Detail, Node004, Notification: Notification - Node Dead

//Zamb

zamb · October 29, 2017, 11:06pm

Update: I did a Hard Reset on the device using Domotics, and then I cleared the config in HASS and trigger an include. This is what I got in the log… Dead from the beginning…

2017-10-29 23:54:34.751 Detail, Queuing (Controller) Add Device
2017-10-29 23:54:34.752 Info, Add Device
2017-10-29 23:54:34.752 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2017-10-29 23:54:34.752 Detail, Notification: ControllerCommand - Starting
2017-10-29 23:54:34.755 Detail, 
2017-10-29 23:54:34.755 Info, contrlr, Sending (Command) message (Callback ID=0x0a, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2017-10-29 23:54:34.759 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x01, 0x00, 0x00, 0xb9
2017-10-29 23:54:34.760 Detail, 
2017-10-29 23:54:34.761 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-10-29 23:54:34.762 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2017-10-29 23:54:34.762 Detail, Node001,   Expected callbackId was received
2017-10-29 23:54:34.762 Detail, Node001,   Expected reply was received
2017-10-29 23:54:34.762 Detail, Node001,   Message transaction complete
2017-10-29 23:54:34.762 Detail, 
2017-10-29 23:54:34.763 Detail, contrlr, Removing current message
2017-10-29 23:54:34.763 Detail, Notification: ControllerCommand - Waiting
2017-10-29 23:54:46.426 Detail,   Received: 0x01, 0x1a, 0x00, 0x4a, 0x0a, 0x02, 0x04, 0x13, 0x04, 0x10, 0x01, 0x5e, 0x86, 0x72, 0x5a, 0x73, 0x20, 0x27, 0x25, 0x32, 0x60, 0x85, 0x8e, 0x59, 0x70, 0xef, 0x20, 0xbb
2017-10-29 23:54:46.427 Detail, 
2017-10-29 23:54:46.427 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-10-29 23:54:46.427 Info, ADD_NODE_STATUS_NODE_FOUND
2017-10-29 23:54:46.427 Detail, Notification: ControllerCommand - InProgress
2017-10-29 23:54:46.431 Detail,   Received: 0x01, 0x1a, 0x00, 0x4a, 0x0a, 0x03, 0x04, 0x13, 0x04, 0x10, 0x01, 0x5e, 0x86, 0x72, 0x5a, 0x73, 0x20, 0x27, 0x25, 0x32, 0x60, 0x85, 0x8e, 0x59, 0x70, 0xef, 0x20, 0xba
2017-10-29 23:54:46.431 Detail, 
2017-10-29 23:54:46.431 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-10-29 23:54:46.431 Info, ADD_NODE_STATUS_ADDING_SLAVE
2017-10-29 23:54:46.431 Info, Adding node ID 4 - Secure
2017-10-29 23:54:46.432 Detail, Notification: ControllerCommand - InProgress
2017-10-29 23:54:54.151 Detail,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x05, 0x04, 0x00, 0xb9
2017-10-29 23:54:54.151 Detail, 
2017-10-29 23:54:54.151 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-10-29 23:54:54.152 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2017-10-29 23:54:54.152 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2017-10-29 23:54:54.152 Detail, Notification: ControllerCommand - InProgress
2017-10-29 23:54:54.153 Detail, 
2017-10-29 23:54:54.153 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2017-10-29 23:54:54.304 Detail, contrlr,   Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x06, 0x04, 0x00, 0xba
2017-10-29 23:54:54.304 Detail, 
2017-10-29 23:54:54.304 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-10-29 23:54:54.304 Info, contrlr, ADD_NODE_STATUS_DONE
2017-10-29 23:54:54.314 Info, Node004,   Basic device class    (0x04) - Routing Slave
2017-10-29 23:54:54.314 Info, Node004,   Generic device Class  (0x10) - Binary Switch
2017-10-29 23:54:54.315 Info, Node004,   Specific device class (0x01) - Binary Power Switch
2017-10-29 23:54:54.315 Info, Node004,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_SWITCH_BINARY
2017-10-29 23:54:54.315 Info, Node004,   Mandatory Command Classes for Node 4:
2017-10-29 23:54:54.315 Info, Node004,     COMMAND_CLASS_BASIC
2017-10-29 23:54:54.315 Info, Node004,     COMMAND_CLASS_SWITCH_BINARY
2017-10-29 23:54:54.315 Info, Node004,     COMMAND_CLASS_SWITCH_ALL
2017-10-29 23:54:54.316 Info, Node004,   Mandatory Command Classes controlled by Node 4:
2017-10-29 23:54:54.316 Info, Node004,     None
2017-10-29 23:54:54.316 Info, Node004,   Optional command classes for node 4:
2017-10-29 23:54:54.316 Info, Node004,     COMMAND_CLASS_ZWAVE_PLUS_INFO
2017-10-29 23:54:54.316 Info, Node004,     COMMAND_CLASS_VERSION
2017-10-29 23:54:54.316 Info, Node004,     COMMAND_CLASS_MANUFACTURER_SPECIFIC
2017-10-29 23:54:54.320 Info, Node004,     COMMAND_CLASS_DEVICE_RESET_LOCALLY
2017-10-29 23:54:54.320 Info, Node004,     COMMAND_CLASS_POWERLEVEL
2017-10-29 23:54:54.320 Info, Node004,     COMMAND_CLASS_BASIC (Existing)
2017-10-29 23:54:54.320 Info, Node004,     COMMAND_CLASS_SWITCH_ALL (Existing)
2017-10-29 23:54:54.320 Info, Node004,     COMMAND_CLASS_SWITCH_BINARY (Existing)
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_METER
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_MULTI_INSTANCE/CHANNEL
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_ASSOCIATION
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION
2017-10-29 23:54:54.321 Info, Node004,   CommandClass 0x59 - NOT REQUIRED
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_CONFIGURATION
2017-10-29 23:54:54.321 Info, Node004,   Optional command classes controlled by node 4:
2017-10-29 23:54:54.321 Info, Node004,     COMMAND_CLASS_BASIC (Existing)
2017-10-29 23:54:54.322 Info, Node004,     None
2017-10-29 23:54:54.322 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2017-10-29 23:54:54.322 Detail, Node004, QueryStage_ProtocolInfo
2017-10-29 23:54:54.322 Detail, Node004, Queuing (Query) Get Node Protocol Info (Node=4): 0x01, 0x04, 0x00, 0x41, 0x04, 0xbe
2017-10-29 23:54:54.322 Detail, Node004, Queuing (Query) Query Stage Complete (ProtocolInfo)
2017-10-29 23:54:54.323 Info, Node004, Initializing Node. New Node: true (true)
2017-10-29 23:54:54.323 Detail, Node006,   Expected reply was received
2017-10-29 23:54:54.323 Detail, Node006,   Message transaction complete
2017-10-29 23:54:54.323 Detail, 
2017-10-29 23:54:54.323 Detail, contrlr, Removing current message
2017-10-29 23:54:54.323 Detail, Node004, Notification: NodeAdded
2017-10-29 23:54:54.341 Detail, Node004, Notification: NodeProtocolInfo
2017-10-29 23:54:54.346 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.364 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.368 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.376 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.383 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.387 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.394 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.400 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.406 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.424 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.431 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.439 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.446 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.458 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.474 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.480 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.482 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.492 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.505 Detail, Node004, Notification: ValueAdded
2017-10-29 23:54:54.558 Detail, Notification: ControllerCommand - Completed
2017-10-29 23:54:54.560 Detail, 
2017-10-29 23:54:54.560 Info, Node004, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=4): 0x01, 0x04, 0x00, 0x41, 0x04, 0xbe
2017-10-29 23:54:54.563 Detail, Node004,   Received: 0x01, 0x09, 0x01, 0x41, 0xd3, 0x9c, 0x01, 0x04, 0x10, 0x01, 0xed
2017-10-29 23:54:54.563 Detail, 
2017-10-29 23:54:54.563 Info, Node004, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2017-10-29 23:54:54.564 Info, Node004,   Protocol Info for Node 4:
2017-10-29 23:54:54.564 Info, Node004,     Listening     = true
2017-10-29 23:54:54.564 Info, Node004,     Beaming       = true
2017-10-29 23:54:54.564 Info, Node004,     Routing       = true
2017-10-29 23:54:54.564 Info, Node004,     Max Baud Rate = 40000
2017-10-29 23:54:54.564 Info, Node004,     Version       = 4
2017-10-29 23:54:54.564 Info, Node004,     Security      = false
2017-10-29 23:54:54.564 Detail, Node156,   Expected reply was received
2017-10-29 23:54:54.564 Detail, Node156,   Message transaction complete
2017-10-29 23:54:54.564 Detail, 
2017-10-29 23:54:54.564 Detail, Node004, Removing current message
2017-10-29 23:54:54.565 Detail, Node004, Query Stage Complete (ProtocolInfo)
2017-10-29 23:54:54.565 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
2017-10-29 23:54:54.565 Detail, Node004, QueryStage_Probe
2017-10-29 23:54:54.565 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 23:54:54.565 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2017-10-29 23:54:54.565 Detail, Node004, Queuing (Query) Query Stage Complete (Probe)
2017-10-29 23:54:54.565 Detail, 
2017-10-29 23:54:54.566 Info, Node004, Sending (NoOp) message (Callback ID=0x0b, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2017-10-29 23:54:54.578 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 23:54:54.579 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 23:54:55.485 Detail, Node004,   Received: 0x01, 0x19, 0x00, 0x49, 0x84, 0x04, 0x13, 0x04, 0x10, 0x01, 0x5e, 0x86, 0x72, 0x5a, 0x73, 0x20, 0x27, 0x25, 0x32, 0x60, 0x85, 0x8e, 0x59, 0x70, 0xef, 0x20, 0x37
2017-10-29 23:54:55.485 Detail, 
2017-10-29 23:54:55.485 Info, Node004, UPDATE_STATE_NODE_INFO_RECEIVED from node 4
2017-10-29 23:54:55.485 Detail, Node004, AdvanceQueries queryPending=1 queryRetries=0 queryStage=Probe live=1
2017-10-29 23:54:58.675 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0b, 0x01, 0x01, 0x9a, 0x7a
2017-10-29 23:54:58.676 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2017-10-29 23:54:58.676 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 23:54:58.676 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 23:54:58.676 Info, Node004, QueryStageRetry stage Probe requested stage Probe max 3 retries 0 pending 1
2017-10-29 23:54:58.676 Detail, Node001,   Expected callbackId was received
2017-10-29 23:54:58.676 Detail, Node001,   Expected reply was received
2017-10-29 23:54:58.676 Detail, Node001,   Message transaction complete
2017-10-29 23:54:58.677 Detail, 
2017-10-29 23:54:58.677 Detail, Node004, Removing current message
2017-10-29 23:54:58.677 Detail, Node004, Notification: Notification - NoOperation
2017-10-29 23:54:58.681 Detail, Node004, Query Stage Complete (Probe)
2017-10-29 23:54:58.681 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=1 queryStage=Probe live=1
2017-10-29 23:54:58.681 Detail, Node004, QueryStage_Probe
2017-10-29 23:54:58.682 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 23:54:58.682 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0c, 0xca
2017-10-29 23:54:58.682 Detail, Node004, Queuing (Query) Query Stage Complete (Probe)
2017-10-29 23:54:58.682 Detail, 
2017-10-29 23:54:58.682 Info, Node004, Sending (NoOp) message (Callback ID=0x0c, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0c, 0xca
2017-10-29 23:54:58.694 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 23:54:58.695 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 23:55:02.853 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0c, 0x01, 0x01, 0xa1, 0x46
2017-10-29 23:55:02.854 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2017-10-29 23:55:02.854 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 23:55:02.854 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 23:55:02.854 Info, Node004, QueryStageRetry stage Probe requested stage Probe max 3 retries 1 pending 1
2017-10-29 23:55:02.854 Detail, Node001,   Expected callbackId was received
2017-10-29 23:55:02.854 Detail, Node001,   Expected reply was received
2017-10-29 23:55:02.855 Detail, Node001,   Message transaction complete
2017-10-29 23:55:02.855 Detail, 
2017-10-29 23:55:02.855 Detail, Node004, Removing current message
2017-10-29 23:55:02.855 Detail, Node004, Notification: Notification - NoOperation
2017-10-29 23:55:02.859 Detail, Node004, Query Stage Complete (Probe)
2017-10-29 23:55:02.859 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=2 queryStage=Probe live=1
2017-10-29 23:55:02.859 Detail, Node004, QueryStage_Probe
2017-10-29 23:55:02.860 Info, Node004, NoOperation::Set - Routing=true
2017-10-29 23:55:02.860 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xcb
2017-10-29 23:55:02.860 Detail, Node004, Queuing (Query) Query Stage Complete (Probe)
2017-10-29 23:55:02.860 Detail, 
2017-10-29 23:55:02.861 Info, Node004, Sending (NoOp) message (Callback ID=0x0d, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xcb
2017-10-29 23:55:02.873 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-10-29 23:55:02.874 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2017-10-29 23:55:06.988 Detail, Node004,   Received: 0x01, 0x07, 0x00, 0x13, 0x0d, 0x01, 0x01, 0x9c, 0x7a
2017-10-29 23:55:06.988 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2017-10-29 23:55:06.988 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2017-10-29 23:55:06.988 Warning, Node004, WARNING: Device is not a sleeping node.
2017-10-29 23:55:06.988 Error, Node004, ERROR: node presumed dead
2017-10-29 23:55:06.989 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2017-10-29 23:55:06.989 Info, Node004, QueryStageRetry stage Probe requested stage Probe max 3 retries 2 pending 1
2017-10-29 23:55:06.989 Detail, Node001,   Expected callbackId was received
2017-10-29 23:55:06.989 Detail, Node001,   Expected reply was received
2017-10-29 23:55:06.989 Detail, Node001,   Message transaction complete
2017-10-29 23:55:06.989 Detail, 
2017-10-29 23:55:06.989 Detail, Node004, Removing current message
2017-10-29 23:55:06.990 Detail, Node004, Notification: Notification - NoOperation
2017-10-29 23:55:06.993 Detail, Node004, Notification: Notification - Node Dead
2017-10-29 23:55:06.998 Detail, Node004, Query Stage Complete (Probe)
2017-10-29 23:55:06.998 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=0

//Zamb

zamb · November 10, 2017, 6:40am

This was solved by getting a new USB device (Zwave.me UZB). The first one I had was faulty.