I’m having trouble getting my Trane thermostat to play nice with Home Assistant and Z-Wave. Here’s the link to the thermostat’s PDF.
I have an Aeotec ZW090 Z-Stick Gen5 US. Plugged in to a Pi running Hassbian. I set the thermostat to Learning mode (page 29 of the PDF) and then hit Add Node in Home Assistant. It added it, but the thermostat is not registering as a climate device.
configuration.yaml
zwave:
usb_path: /dev/zwave
network_key: !secret zwave_network_key
climate:
- platform: zwave
Entity information for the thermostat:
query_stage: Complete
is_info_received: true
node_id: 4
receivedCnt: 1
receivedDups: 0
sentCnt: 4
is_zwave_plus: true
receivedUnsolicited: 0
averageResponseRTT: 150
receivedTS: 2018-04-05 15:11:11:761
averageRequestRTT: 29
capabilities: routing,listening,beaming
sentFailed: 2
lastResponseRTT: 150
node_name: Trane Unknown: type=5441, id=3530
lastRequestRTT: 29
sentTS: 2018-04-05 15:11:11:611
max_baud_rate: 40000
product_name: Unknown: type=5441, id=3530
friendly_name: Trane Unknown: type=5441, id=3530
is_awake: true
is_ready: true
retries: 0
is_failed: false
manufacturer_name: Trane
I can find no errors in home-assistant.log
. I have included OZW_Log.txt
below:
2018-04-05 15:10:25.898 Always, OpenZwave Version 1.4.2926 Starting Up
2018-04-05 15:11:01.122 Info, Setting Up Provided Network Key for Secure Communications
2018-04-05 15:11:01.123 Info, mgr, Added driver for controller /dev/zwave
2018-04-05 15:11:01.123 Info, Opening controller /dev/zwave
2018-04-05 15:11:01.123 Info, Trying to open serial port /dev/zwave (attempt 1)
2018-04-05 15:11:01.123 Info, Serial port /dev/zwave opened (attempt 1)
2018-04-05 15:11:01.124 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2018-04-05 15:11:01.124 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2018-04-05 15:11:01.124 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2018-04-05 15:11:01.124 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2018-04-05 15:11:01.124 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2018-04-05 15:11:01.124 Detail,
2018-04-05 15:11:01.124 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2018-04-05 15:11:01.126 Detail, contrlr, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x33, 0x2e, 0x39, 0x35, 0x00, 0x01, 0x99
2018-04-05 15:11:01.126 Detail,
2018-04-05 15:11:01.126 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2018-04-05 15:11:01.126 Info, contrlr, Static Controller library, version Z-Wave 3.95
2018-04-05 15:11:01.126 Detail, Node045, Expected reply was received
2018-04-05 15:11:01.126 Detail, Node045, Message transaction complete
2018-04-05 15:11:01.126 Detail,
2018-04-05 15:11:01.126 Detail, contrlr, Removing current message
2018-04-05 15:11:01.127 Detail,
2018-04-05 15:11:01.127 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2018-04-05 15:11:01.129 Detail, contrlr, Received: 0x01, 0x08, 0x01, 0x20, 0xfd, 0xfa, 0xe7, 0x80, 0x01, 0xb7
2018-04-05 15:11:01.129 Detail,
2018-04-05 15:11:01.129 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfdfae780. Our node ID = 1
2018-04-05 15:11:01.129 Detail, Node250, Expected reply was received
2018-04-05 15:11:01.129 Detail, Node250, Message transaction complete
2018-04-05 15:11:01.129 Detail,
2018-04-05 15:11:01.130 Detail, contrlr, Removing current message
2018-04-05 15:11:01.130 Detail,
2018-04-05 15:11:01.130 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2018-04-05 15:11:01.132 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2018-04-05 15:11:01.132 Detail,
2018-04-05 15:11:01.132 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2018-04-05 15:11:01.132 Info, contrlr, There is no SUC ID Server (SIS) in this network.
2018-04-05 15:11:01.132 Info, contrlr, The PC controller is a primary controller.
2018-04-05 15:11:01.132 Detail, Node247, Expected reply was received
2018-04-05 15:11:01.132 Detail, Node247, Message transaction complete
2018-04-05 15:11:01.132 Detail,
2018-04-05 15:11:01.132 Detail, contrlr, Removing current message
2018-04-05 15:11:01.132 Detail,
2018-04-05 15:11:01.132 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2018-04-05 15:11:01.136 Detail, contrlr, Received: 0x01, 0x2b, 0x01, 0x07, 0x01, 0x00, 0x00, 0x86, 0x01, 0x01, 0x00, 0x5a, 0xfe, 0x81, 0xff, 0x88, 0x4f, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x00, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x60, 0x00, 0x00, 0xfa
2018-04-05 15:11:01.136 Detail,
2018-04-05 15:11:01.136 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2018-04-05 15:11:01.136 Info, contrlr, Serial API Version: 1.0
2018-04-05 15:11:01.136 Info, contrlr, Manufacturer ID: 0x0086
2018-04-05 15:11:01.136 Info, contrlr, Product Type: 0x0101
2018-04-05 15:11:01.136 Info, contrlr, Product ID: 0x005a
2018-04-05 15:11:01.136 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2018-04-05 15:11:01.136 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2018-04-05 15:11:01.136 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2018-04-05 15:11:01.136 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2018-04-05 15:11:01.136 Detail, Expected reply was received
2018-04-05 15:11:01.136 Detail, Message transaction complete
2018-04-05 15:11:01.136 Detail,
2018-04-05 15:11:01.136 Detail, contrlr, Removing current message
2018-04-05 15:11:01.136 Detail,
2018-04-05 15:11:01.137 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2018-04-05 15:11:01.138 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2018-04-05 15:11:01.139 Detail,
2018-04-05 15:11:01.139 Info, contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0
2018-04-05 15:11:01.139 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2018-04-05 15:11:01.139 Detail, Node172, Expected reply was received
2018-04-05 15:11:01.139 Detail, Node172, Message transaction complete
2018-04-05 15:11:01.139 Detail,
2018-04-05 15:11:01.139 Detail, contrlr, Removing current message
2018-04-05 15:11:01.139 Detail,
2018-04-05 15:11:01.139 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2018-04-05 15:11:01.194 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x46, 0xc2, 0x93, 0x1e, 0x3b, 0xa6, 0xb7, 0xad, 0x35, 0xbf, 0xb6, 0x57, 0xae, 0x0e, 0x37, 0x3c, 0x14, 0x82, 0x87, 0xee, 0x1a, 0xf1, 0x04, 0x88, 0x37, 0x44, 0x33, 0xa8, 0x5b, 0x1f, 0x20, 0x02, 0xbe
2018-04-05 15:11:01.195 Detail,
2018-04-05 15:11:01.195 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2018-04-05 15:11:01.195 Detail, Node032, Expected reply was received
2018-04-05 15:11:01.195 Detail, Node032, Message transaction complete
2018-04-05 15:11:01.195 Detail,
2018-04-05 15:11:01.195 Detail, contrlr, Removing current message
2018-04-05 15:11:01.195 Detail,
2018-04-05 15:11:01.195 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2018-04-05 15:11:01.296 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
2018-04-05 15:11:01.297 Detail,
2018-04-05 15:11:01.297 Info, mgr, Driver with Home ID of 0xfdfae780 is now ready.
2018-04-05 15:11:01.297 Info,
2018-04-05 15:11:01.299 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2018-04-05 15:11:01.299 Info, contrlr, Node 001 - Known
2018-04-05 15:11:01.299 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2018-04-05 15:11:01.299 Detail, Node001, QueryStage_CacheLoad
2018-04-05 15:11:01.299 Info, Node001, Node Identity Codes: 0086:0101:005a
2018-04-05 15:11:01.300 Detail, Node001, QueryStage_Associations
2018-04-05 15:11:01.300 Detail, Node001, QueryStage_Neighbors
2018-04-05 15:11:01.300 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2018-04-05 15:11:01.300 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2018-04-05 15:11:01.300 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2018-04-05 15:11:01.300 Info, contrlr, Node 004 - Known
2018-04-05 15:11:01.300 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2018-04-05 15:11:01.300 Detail, Node004, QueryStage_CacheLoad
2018-04-05 15:11:01.300 Info, Node004, Node Identity Codes: 008b:5441:3530
2018-04-05 15:11:01.300 Info, Node004, NoOperation::Set - Routing=true
2018-04-05 15:11:01.300 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcc
2018-04-05 15:11:01.300 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2018-04-05 15:11:01.300 Detail, Expected reply was received
2018-04-05 15:11:01.300 Detail, Message transaction complete
2018-04-05 15:11:01.300 Detail,
2018-04-05 15:11:01.300 Detail, contrlr, Removing current message
2018-04-05 15:11:01.300 Detail, Node001, Notification: DriverReady
2018-04-05 15:11:01.345 Detail, Node001, Notification: NodeAdded
2018-04-05 15:11:01.421 Detail, Node001, Notification: NodeProtocolInfo
2018-04-05 15:11:01.423 Detail, Node001, Notification: EssentialNodeQueriesComplete
2018-04-05 15:11:01.427 Detail, Node001, Notification: ValueAdded
2018-04-05 15:11:01.432 Detail, Node001, Notification: NodeNaming
2018-04-05 15:11:01.433 Detail, Node004, Notification: NodeAdded
2018-04-05 15:11:01.475 Detail, Node004, Notification: NodeProtocolInfo
2018-04-05 15:11:01.476 Detail, Node004, Notification: EssentialNodeQueriesComplete
2018-04-05 15:11:01.477 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.480 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.481 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.482 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.488 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.488 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.489 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.490 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.491 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.493 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.495 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.499 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.504 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.516 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.520 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.523 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.524 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.526 Detail, Node004, Notification: Group
2018-04-05 15:11:01.531 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.536 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.538 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.541 Detail, Node004, Notification: ValueAdded
2018-04-05 15:11:01.547 Detail, Node004, Notification: NodeNaming
2018-04-05 15:11:01.556 Detail,
2018-04-05 15:11:01.556 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2018-04-05 15:11:01.558 Detail, contrlr, Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2018-04-05 15:11:01.558 Detail,
2018-04-05 15:11:01.558 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2018-04-05 15:11:01.558 Detail, Node015, Expected reply was received
2018-04-05 15:11:01.559 Detail, Node015, Message transaction complete
2018-04-05 15:11:01.559 Detail,
2018-04-05 15:11:01.559 Detail, contrlr, Removing current message
2018-04-05 15:11:01.559 Detail,
2018-04-05 15:11:01.559 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
2018-04-05 15:11:01.561 Detail, contrlr, Removing current message
2018-04-05 15:11:01.561 Detail,
2018-04-05 15:11:01.561 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
2018-04-05 15:11:01.565 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
2018-04-05 15:11:01.565 Detail,
2018-04-05 15:11:01.565 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2018-04-05 15:11:01.566 Info, Node001, Neighbors of this node are:
2018-04-05 15:11:01.566 Info, Node001, (none reported)
2018-04-05 15:11:01.566 Detail, Expected reply was received
2018-04-05 15:11:01.566 Detail, Message transaction complete
2018-04-05 15:11:01.566 Detail,
2018-04-05 15:11:01.566 Detail, Node001, Removing current message
2018-04-05 15:11:01.566 Detail,
2018-04-05 15:11:01.566 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
2018-04-05 15:11:01.582 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-04-05 15:11:01.582 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack
2018-04-05 15:11:01.595 Detail, Node004, Received: 0x01, 0x07, 0x00, 0x13, 0x0a, 0x00, 0x00, 0x02, 0xe3
2018-04-05 15:11:01.595 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2018-04-05 15:11:01.595 Info, Node004, Request RTT 29 Average Request RTT 29
2018-04-05 15:11:01.595 Detail, Expected callbackId was received
2018-04-05 15:11:01.595 Detail, Expected reply was received
2018-04-05 15:11:01.595 Detail, Message transaction complete
2018-04-05 15:11:01.595 Detail,
2018-04-05 15:11:01.595 Detail, Node004, Removing current message
2018-04-05 15:11:01.595 Detail, Node004, Notification: Notification - NoOperation
2018-04-05 15:11:01.597 Detail, Node001, Query Stage Complete (Neighbors)
2018-04-05 15:11:01.597 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2018-04-05 15:11:01.597 Detail, Node001, QueryStage_Session
2018-04-05 15:11:01.597 Detail, Node001, QueryStage_Dynamic
2018-04-05 15:11:01.597 Detail, Node001, QueryStage_Configuration
2018-04-05 15:11:01.597 Detail, Node001, QueryStage_Complete
2018-04-05 15:11:01.597 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2018-04-05 15:11:01.597 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2018-04-05 15:11:01.598 Detail, Node001, Notification: NodeQueriesComplete
2018-04-05 15:11:01.602 Detail, Node004, Query Stage Complete (CacheLoad)
2018-04-05 15:11:01.602 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2018-04-05 15:11:01.602 Detail, Node004, QueryStage_Associations
2018-04-05 15:11:01.602 Info, Node004, Number of association groups reported for node 4 is 1.
2018-04-05 15:11:01.602 Info, Node004, Get Associations for group 1 of node 4
2018-04-05 15:11:01.602 Detail, Node004, Queuing (Send) AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0b, 0x49
2018-04-05 15:11:01.602 Detail, Node004, Queuing (Query) Query Stage Complete (Associations)
2018-04-05 15:11:01.602 Detail,
2018-04-05 15:11:01.602 Info, Node004, Sending (Send) message (Callback ID=0x0b, Expected Reply=0x04) - AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0b, 0x49
2018-04-05 15:11:01.611 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-04-05 15:11:01.611 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack
2018-04-05 15:11:05.683 Detail, Node004, Received: 0x01, 0x07, 0x00, 0x13, 0x0b, 0x01, 0x01, 0x98, 0x78
2018-04-05 15:11:05.769 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2018-04-05 15:11:05.769 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2018-04-05 15:11:05.769 Warning, Node004, WARNING: Device is not a sleeping node.
2018-04-05 15:11:05.769 Detail, Node001, Expected callbackId was received
2018-04-05 15:11:11.603 Error, Node004, ERROR: Dropping command, expected response not received after 1 attempt(s)
2018-04-05 15:11:11.603 Detail, Node004, Removing current message
2018-04-05 15:11:11.603 Detail, Node004, Notification: Notification - TimeOut
2018-04-05 15:11:11.605 Detail, Node004, Query Stage Complete (Associations)
2018-04-05 15:11:11.605 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2018-04-05 15:11:11.605 Detail, Node004, QueryStage_Neighbors
2018-04-05 15:11:11.605 Detail, Requesting routing info (neighbor list) for Node 4
2018-04-05 15:11:11.605 Detail, Node004, Queuing (Command) Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2018-04-05 15:11:11.605 Detail, Node004, Queuing (Query) Query Stage Complete (Neighbors)
2018-04-05 15:11:11.605 Detail,
2018-04-05 15:11:11.605 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
2018-04-05 15:11:11.610 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
2018-04-05 15:11:11.610 Detail,
2018-04-05 15:11:11.610 Info, Node004, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2018-04-05 15:11:11.610 Info, Node004, Neighbors of this node are:
2018-04-05 15:11:11.610 Info, Node004, (none reported)
2018-04-05 15:11:11.610 Detail, Expected reply was received
2018-04-05 15:11:11.610 Detail, Message transaction complete
2018-04-05 15:11:11.610 Detail,
2018-04-05 15:11:11.610 Detail, Node004, Removing current message
2018-04-05 15:11:11.610 Detail, Node004, Query Stage Complete (Neighbors)
2018-04-05 15:11:11.610 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2018-04-05 15:11:11.610 Detail, Node004, QueryStage_Session
2018-04-05 15:11:11.610 Detail, Node004, Queuing (Query) PowerlevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x73, 0x02, 0x25, 0x0c, 0xbb
2018-04-05 15:11:11.610 Detail, Node004, Queuing (Query) Query Stage Complete (Session)
2018-04-05 15:11:11.610 Detail,
2018-04-05 15:11:11.611 Info, Node004, Sending (Query) message (Callback ID=0x0c, Expected Reply=0x04) - PowerlevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x73, 0x02, 0x25, 0x0c, 0xbb
2018-04-05 15:11:11.623 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-04-05 15:11:11.623 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack
2018-04-05 15:11:11.761 Detail, Node004, Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x04, 0x04, 0x73, 0x03, 0x00, 0x00, 0x81
2018-04-05 15:11:11.761 Detail,
2018-04-05 15:11:11.761 Info, Node004, Response RTT 150 Average Response RTT 150
2018-04-05 15:11:11.761 Info, Node004, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2018-04-05 15:11:11.761 Detail, Node004, Initial read of value
2018-04-05 15:11:11.761 Detail, Node004, Initial read of value
2018-04-05 15:11:11.762 Detail, Node004, Notification: ValueChanged
2018-04-05 15:11:11.765 Detail, Node004, Notification: ValueChanged
2018-04-05 15:11:15.751 Detail, Node004, Received: 0x01, 0x07, 0x00, 0x13, 0x0c, 0x01, 0x01, 0x9e, 0x79
2018-04-05 15:11:15.751 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2018-04-05 15:11:15.751 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2018-04-05 15:11:15.751 Warning, Node004, WARNING: Device is not a sleeping node.
2018-04-05 15:11:15.751 Detail, Node001, Expected callbackId was received
2018-04-05 15:11:21.611 Error, Node004, ERROR: Dropping command, expected response not received after 1 attempt(s)
2018-04-05 15:11:21.611 Detail, Node004, Removing current message
2018-04-05 15:11:21.612 Detail, Node004, Notification: Notification - TimeOut
2018-04-05 15:11:21.615 Detail, Node004, Query Stage Complete (Session)
2018-04-05 15:11:21.615 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2018-04-05 15:11:21.615 Detail, Node004, QueryStage_Dynamic
2018-04-05 15:11:21.615 Detail, Node004, Queuing (Query) Query Stage Complete (Dynamic)
2018-04-05 15:11:21.616 Detail, Node004, Query Stage Complete (Dynamic)
2018-04-05 15:11:21.616 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2018-04-05 15:11:21.616 Detail, Node004, QueryStage_Configuration
2018-04-05 15:11:21.616 Detail, Node004, QueryStage_Complete
2018-04-05 15:11:21.616 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2018-04-05 15:11:21.616 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2018-04-05 15:11:21.616 Info, Node query processing complete.
2018-04-05 15:11:21.616 Detail, Node004, Notification: NodeQueriesComplete
2018-04-05 15:11:21.622 Detail, contrlr, Notification: AllNodesQueried
Thanks for the help, all.