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