Aeotec (Foxx) extender misbehaviour.

Topics (not sure which fora)
when not sure where to post, post here and mods will move it to right forum.

Moderators: leecollings, remb0

Post Reply
jmansion
Posts: 5
Joined: Tuesday 05 January 2016 0:31
Target OS: Raspberry Pi / ODroid
Domoticz version:
Contact:
Contact jmansion

Aeotec (Foxx) extender misbehaviour.

Post by jmansion »

Having managed to get a Foxx extender to register, I find that it doesn't seem to respond properly.

Does anyone else have experience of them?

I put my UZB into debug mode. This is with v3.5818, W64.

First, on the Console Window, I now see:
OpenZWave: using config in ...
(about 20 seconds later) Error: OpenZWave: Driver Failed!!

I don't think this happened before, but I can't swear to it. There is nothing in OZW_Log.txt at that time, but I continue to receive messages from the temp node (and others) afterwards.

In OZW_Log.txt, I see communications errors with node007 the extended, and eventually it has been marked dead - that happened shortly after I enabled polling on it because 'last seen' pretty much corresponded to when I reset it and joined it to the network.

I turned it on and joined it at 06:48 or so. Some problems are logged but it seems to be working in some capacity. Scroll down for what happens later
when I turn on polling - the node is quickly marked as dead. This node is about 5m from my PC with the UZB, line of sight straight through a doorway.


2016-10-26 06:48:19.147 Detail, Queuing (Controller) Add Device
2016-10-26 06:48:19.151 Info, Add Device
2016-10-26 06:48:19.154 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x37, 0x46
2016-10-26 06:48:19.157 Detail, Notification: ControllerComand - Starting
2016-10-26 06:48:19.160 Detail,
2016-10-26 06:48:19.163 Info, contrlr, Sending (Command) message (Callback ID=0x37, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x37, 0x46
2016-10-26 06:48:19.168 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x37, 0x01, 0x00, 0x00, 0x84
2016-10-26 06:48:19.170 Detail,
2016-10-26 06:48:19.173 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2016-10-26 06:48:19.176 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2016-10-26 06:48:19.178 Detail, Node001, Expected callbackId was received
2016-10-26 06:48:19.180 Detail, Node001, Expected reply was received
2016-10-26 06:48:19.183 Detail, Node001, Message transaction complete
2016-10-26 06:48:19.185 Detail,
2016-10-26 06:48:19.188 Detail, contrlr, Removing current message
2016-10-26 06:48:19.190 Detail, Notification: ControllerCommand - Waiting
2016-10-26 06:48:27.152 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x37, 0x02, 0x00, 0x00, 0x87
2016-10-26 06:48:27.154 Detail,
2016-10-26 06:48:27.156 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2016-10-26 06:48:27.159 Info, ADD_NODE_STATUS_NODE_FOUND
2016-10-26 06:48:27.161 Detail, Notification: ControllerCommand - InProgress
2016-10-26 06:48:27.307 Detail, Received: 0x01, 0x10, 0x00, 0x4a, 0x37, 0x03, 0x07, 0x09, 0x04, 0x0f, 0x01, 0x20, 0x70, 0x72, 0x85, 0x73, 0x86, 0xc7
2016-10-26 06:48:27.310 Detail,
2016-10-26 06:48:27.312 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2016-10-26 06:48:27.314 Info, ADD_NODE_STATUS_ADDING_SLAVE
2016-10-26 06:48:27.316 Info, Adding node ID 7 - Non-Secure
2016-10-26 06:48:27.318 Detail, Notification: ControllerCommand - InProgress
2016-10-26 06:48:35.407 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x37, 0x05, 0x07, 0x00, 0x87
2016-10-26 06:48:35.410 Detail,
2016-10-26 06:48:35.412 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2016-10-26 06:48:35.415 Info, ADD_NODE_STATUS_PROTOCOL_DONE
2016-10-26 06:48:35.417 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2016-10-26 06:48:35.419 Detail, Notification: ControllerCommand - InProgress
2016-10-26 06:48:35.421 Detail,
2016-10-26 06:48:35.423 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2016-10-26 06:48:35.576 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x37, 0x06, 0x07, 0x00, 0x84
2016-10-26 06:48:35.586 Detail,
2016-10-26 06:48:35.596 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2016-10-26 06:48:35.606 Info, contrlr, ADD_NODE_STATUS_DONE
2016-10-26 06:48:35.621 Info, Node007, Basic device class (0x04) - Routing Slave
2016-10-26 06:48:35.631 Info, Node007, Generic device Class (0x0f) - Repeater Slave
2016-10-26 06:48:35.640 Info, Node007, Specific device class (0x01) - Basic Repeater Slave
2016-10-26 06:48:35.650 Info, Node007, COMMAND_CLASS_BASIC is not mapped
2016-10-26 06:48:35.656 Info, Node007, Mandatory Command Classes for Node 7:
2016-10-26 06:48:35.662 Info, Node007, COMMAND_CLASS_BASIC
2016-10-26 06:48:35.667 Info, Node007, Mandatory Command Classes controlled by Node 7:
2016-10-26 06:48:35.673 Info, Node007, None
2016-10-26 06:48:35.679 Info, Node007, Optional command classes for node 7:
2016-10-26 06:48:35.683 Info, Node007, COMMAND_CLASS_BASIC (Existing)
2016-10-26 06:48:35.687 Info, Node007, COMMAND_CLASS_CONFIGURATION
2016-10-26 06:48:35.690 Info, Node007, COMMAND_CLASS_MANUFACTURER_SPECIFIC
2016-10-26 06:48:35.693 Info, Node007, COMMAND_CLASS_ASSOCIATION
2016-10-26 06:48:35.697 Info, Node007, COMMAND_CLASS_POWERLEVEL
2016-10-26 06:48:35.701 Info, Node007, COMMAND_CLASS_VERSION
2016-10-26 06:48:35.704 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
2016-10-26 06:48:35.708 Detail, Node007, QueryStage_ProtocolInfo
2016-10-26 06:48:35.711 Detail, Node007, Queuing (Query) Get Node Protocol Info (Node=7): 0x01, 0x04, 0x00, 0x41, 0x07, 0xbd
2016-10-26 06:48:35.713 Detail, Node007, Queuing (Query) Query Stage Complete (ProtocolInfo)
2016-10-26 06:48:35.715 Info, Node007, Initilizing Node. New Node: true (true)
2016-10-26 06:48:35.717 Detail, Node006, Expected reply was received
2016-10-26 06:48:35.719 Detail, Node006, Message transaction complete
2016-10-26 06:48:35.721 Detail,
2016-10-26 06:48:35.723 Detail, contrlr, Removing current message
2016-10-26 06:48:35.725 Detail, Node007, Notification: NodeAdded
2016-10-26 06:48:35.803 Info, mgr, Manager::WriteConfig completed for driver with home ID of 0xfbd082c4
2016-10-26 06:48:35.812 Detail, Node007, Notification: NodeProtocolInfo
2016-10-26 06:48:35.819 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.826 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.831 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.836 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.840 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.844 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.848 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.852 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.856 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.860 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.862 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.865 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.867 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.870 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:35.872 Detail, Notification: ControllerCommand - Completed
2016-10-26 06:48:35.874 Detail,
2016-10-26 06:48:35.877 Info, Node007, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=7): 0x01, 0x04, 0x00, 0x41, 0x07, 0xbd
2016-10-26 06:48:35.881 Detail, Node007, Received: 0x01, 0x09, 0x01, 0x41, 0xd3, 0x9c, 0x00, 0x04, 0x0f, 0x01, 0xf3
2016-10-26 06:48:35.883 Detail,
2016-10-26 06:48:35.886 Info, Node007, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2016-10-26 06:48:35.888 Info, Node007, Protocol Info for Node 7:
2016-10-26 06:48:35.891 Info, Node007, Listening = true
2016-10-26 06:48:35.892 Info, Node007, Beaming = true
2016-10-26 06:48:35.894 Info, Node007, Routing = true
2016-10-26 06:48:35.897 Info, Node007, Max Baud Rate = 40000
2016-10-26 06:48:35.899 Info, Node007, Version = 4
2016-10-26 06:48:35.901 Info, Node007, Security = false
2016-10-26 06:48:35.903 Detail, Node156, Expected reply was received
2016-10-26 06:48:35.905 Detail, Node156, Message transaction complete
2016-10-26 06:48:35.907 Detail,
2016-10-26 06:48:35.909 Detail, Node007, Removing current message
2016-10-26 06:48:35.911 Detail, Node007, Query Stage Complete (ProtocolInfo)
2016-10-26 06:48:35.913 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
2016-10-26 06:48:35.915 Detail, Node007, QueryStage_Probe
2016-10-26 06:48:35.917 Info, Node007, NoOperation::Set - Routing=true
2016-10-26 06:48:35.919 Detail, Node007, Queuing (NoOp) NoOperation_Set (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x00, 0x00, 0x25, 0x38, 0xfd
2016-10-26 06:48:35.921 Detail, Node007, Queuing (Query) Query Stage Complete (Probe)
2016-10-26 06:48:35.923 Detail,
2016-10-26 06:48:35.925 Info, Node007, Sending (NoOp) message (Callback ID=0x38, Expected Reply=0x13) - NoOperation_Set (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x00, 0x00, 0x25, 0x38, 0xfd
2016-10-26 06:48:35.938 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:35.940 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:35.955 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x38, 0x00, 0x00, 0x02, 0xd1
2016-10-26 06:48:35.957 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x38 received (expected 0x38)
2016-10-26 06:48:35.959 Info, Node007, Request RTT 32 Average Request RTT 32
2016-10-26 06:48:35.961 Detail, Expected callbackId was received
2016-10-26 06:48:35.963 Detail, Expected reply was received
2016-10-26 06:48:35.965 Detail, Message transaction complete
2016-10-26 06:48:35.968 Detail,
2016-10-26 06:48:35.970 Detail, Node007, Removing current message
2016-10-26 06:48:35.972 Detail, Node007, Notification: Notification - NoOperation
2016-10-26 06:48:35.974 Detail, Node007, Query Stage Complete (Probe)
2016-10-26 06:48:35.976 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1
2016-10-26 06:48:35.977 Detail, Node007, QueryStage_WakeUp
2016-10-26 06:48:35.979 Detail, Node007, QueryStage_ManufacturerSpecific1
2016-10-26 06:48:35.982 Detail, Node007, Checking for ManufacturerSpecific CC and Requesting values if present on this node
2016-10-26 06:48:35.984 Detail, Node007, Queuing (Query) ManufacturerSpecificCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x72, 0x04, 0x25, 0x39, 0x8a
2016-10-26 06:48:35.986 Detail, Node007, Queuing (Query) Query Stage Complete (ManufacturerSpecific1)
2016-10-26 06:48:35.988 Detail,
2016-10-26 06:48:35.989 Info, Node007, Sending (Query) message (Callback ID=0x39, Expected Reply=0x04) - ManufacturerSpecificCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x72, 0x04, 0x25, 0x39, 0x8a
2016-10-26 06:48:35.999 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:36.001 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:36.041 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x39, 0x00, 0x00, 0x05, 0xd7
2016-10-26 06:48:36.046 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x39 received (expected 0x39)
2016-10-26 06:48:36.052 Info, Node007, Request RTT 60 Average Request RTT 46
2016-10-26 06:48:36.058 Detail, Expected callbackId was received
2016-10-26 06:48:36.233 Detail, Node007, Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x07, 0x08, 0x72, 0x05, 0x00, 0x86, 0x00, 0x04, 0x00, 0x25, 0x2a
2016-10-26 06:48:36.244 Detail,
2016-10-26 06:48:36.254 Info, Node007, Response RTT 262 Average Response RTT 262
2016-10-26 06:48:36.279 Info, Node007, Opening config param file C:\Program Files (x86)\Domoticz\Config/aeotec/dsd37.xml
2016-10-26 06:48:36.306 Info, Node007, Received manufacturer specific report from node 7: Manufacturer=Aeotec, Product=DSD37 Range Extender
2016-10-26 06:48:36.314 Info, Node007, Node Identity Codes: 0086:0004:0025
2016-10-26 06:48:36.320 Detail, Node007, Expected reply and command class was received
2016-10-26 06:48:36.326 Detail, Node007, Message transaction complete
2016-10-26 06:48:36.332 Detail,
2016-10-26 06:48:36.337 Detail, Node007, Removing current message
2016-10-26 06:48:36.341 Detail, Node007, Notification: NodeNaming
2016-10-26 06:48:36.414 Detail, Node007, Query Stage Complete (ManufacturerSpecific1)
2016-10-26 06:48:36.422 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=NodeInfo live=1
2016-10-26 06:48:36.430 Detail, Node007, QueryStage_NodePlusInfo
2016-10-26 06:48:36.439 Detail, Node007, QueryStage_SecurityReport
2016-10-26 06:48:36.447 Info, Node007, Opening config param file C:\Program Files (x86)\Domoticz\Config/aeotec/dsd37.xml
2016-10-26 06:48:36.456 Detail, Node007, QueryStage_Versions
2016-10-26 06:48:36.462 Info, Node007, Requesting Versions
2016-10-26 06:48:36.466 Info, Node007, Requesting Versions for COMMAND_CLASS_NO_OPERATION
2016-10-26 06:48:36.470 Info, Node007, Requesting Versions for COMMAND_CLASS_BASIC
2016-10-26 06:48:36.474 Info, Node007, Requesting Versions for COMMAND_CLASS_CONFIGURATION
2016-10-26 06:48:36.479 Info, Node007, Requesting Versions for COMMAND_CLASS_MANUFACTURER_SPECIFIC
2016-10-26 06:48:36.483 Info, Node007, Requesting Versions for COMMAND_CLASS_POWERLEVEL
2016-10-26 06:48:36.487 Info, Node007, Requesting Versions for COMMAND_CLASS_ASSOCIATION
2016-10-26 06:48:36.491 Info, Node007, Requesting Versions for COMMAND_CLASS_VERSION
2016-10-26 06:48:36.493 Detail, Node007, QueryStage_Instances
2016-10-26 06:48:36.496 Info, Node007, Essential node queries are complete
2016-10-26 06:48:36.498 Detail, Node007, QueryStage_Static
2016-10-26 06:48:36.501 Detail, Node007, Queuing (Query) AssociationCmd_GroupingsGet (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x85, 0x05, 0x25, 0x3a, 0x7f
2016-10-26 06:48:36.504 Detail, Node007, Queuing (Query) VersionCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x86, 0x11, 0x25, 0x3b, 0x69
2016-10-26 06:48:36.506 Detail, Node007, Queuing (Query) Query Stage Complete (Static)
2016-10-26 06:48:36.509 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:36.512 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:36.514 Detail, Node007, Notification: ValueAdded
2016-10-26 06:48:36.517 Detail, Node007, Notification: Group
2016-10-26 06:48:36.520 Detail, Node007, Notification: EssentialNodeQueriesComplete
2016-10-26 06:48:36.522 Detail,
2016-10-26 06:48:36.524 Info, Node007, Sending (Query) message (Callback ID=0x3a, Expected Reply=0x04) - AssociationCmd_GroupingsGet (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x85, 0x05, 0x25, 0x3a, 0x7f
2016-10-26 06:48:36.533 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:36.535 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:40.681 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x3a, 0x01, 0x01, 0xa0, 0x71
2016-10-26 06:48:40.681 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x3a received (expected 0x3a)
2016-10-26 06:48:40.697 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:48:40.712 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 06:48:40.712 Detail, Node001, Expected callbackId was received
2016-10-26 06:48:46.533 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)


Dumping queued log messages

2016-10-26 06:48:36.522 4832
2016-10-26 06:48:36.524 4832 Sending (Query) message (Callback ID=0x3a, Expected Reply=0x04) - AssociationCmd_GroupingsGet (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x85, 0x05, 0x25, 0x3a, 0x7f
2016-10-26 06:48:36.533 4832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:36.535 4832 ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:40.681 4832 Received: 0x01, 0x07, 0x00, 0x13, 0x3a, 0x01, 0x01, 0xa0, 0x71
2016-10-26 06:48:40.681 4832 ZW_SEND_DATA Request with callback ID 0x3a received (expected 0x3a)
2016-10-26 06:48:40.697 4832 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:48:40.712 4832 WARNING: Device is not a sleeping node.
2016-10-26 06:48:40.712 4832 Expected callbackId was received
2016-10-26 06:48:46.533 4832 ERROR: Dropping command, expected response not received after 1 attempt(s)

End of queued log message dump


2016-10-26 06:48:46.602 Detail, Node007, Removing current message
2016-10-26 06:48:46.602 Detail, Node007, Notification: Notification - TimeOut
2016-10-26 06:48:46.618 Detail,
2016-10-26 06:48:46.618 Info, Node007, Sending (Query) message (Callback ID=0x3b, Expected Reply=0x04) - VersionCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x86, 0x11, 0x25, 0x3b, 0x69
2016-10-26 06:48:46.638 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:46.639 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:46.655 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x3b, 0x00, 0x00, 0x02, 0xd2
2016-10-26 06:48:46.655 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x3b received (expected 0x3b)
2016-10-26 06:48:46.655 Info, Node007, Request RTT 37 Average Request RTT 41
2016-10-26 06:48:46.655 Detail, Expected callbackId was received
2016-10-26 06:48:46.670 Detail, Node007, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x07, 0x07, 0x86, 0x12, 0x06, 0x03, 0x43, 0x01, 0x06, 0x23
2016-10-26 06:48:46.670 Detail,
2016-10-26 06:48:46.670 Info, Node007, Response RTT 52 Average Response RTT 157
2016-10-26 06:48:46.670 Info, Node007, Received Version report from node 7: Library=6, Protocol=3.67, Application=1.06
2016-10-26 06:48:46.670 Detail, Node007, Initial read of value
2016-10-26 06:48:46.670 Detail, Node007, Initial read of value
2016-10-26 06:48:46.670 Detail, Node007, Initial read of value
2016-10-26 06:48:46.670 Detail, Node007, Expected reply and command class was received
2016-10-26 06:48:46.686 Detail, Node007, Message transaction complete
2016-10-26 06:48:46.686 Detail,
2016-10-26 06:48:46.686 Detail, Node007, Removing current message
2016-10-26 06:48:46.686 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:46.686 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:46.686 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:46.686 Detail, Node007, Query Stage Complete (Static)
2016-10-26 06:48:46.686 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2016-10-26 06:48:46.702 Detail, Node007, QueryStage_Associations
2016-10-26 06:48:46.702 Info, Node007, Number of association groups reported for node 7 is 1.
2016-10-26 06:48:46.702 Info, Node007, Get Associations for group 1 of node 7
2016-10-26 06:48:46.702 Detail, Node007, Queuing (Send) AssociationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x25, 0x3c, 0x7d
2016-10-26 06:48:46.702 Detail, Node007, Queuing (Query) Query Stage Complete (Associations)
2016-10-26 06:48:46.702 Detail,
2016-10-26 06:48:46.702 Info, Node007, Sending (Send) message (Callback ID=0x3c, Expected Reply=0x04) - AssociationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x25, 0x3c, 0x7d
2016-10-26 06:48:46.717 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:46.717 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:46.739 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x3c, 0x00, 0x00, 0x03, 0xd4
2016-10-26 06:48:46.739 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x3c received (expected 0x3c)
2016-10-26 06:48:46.739 Info, Node007, Request RTT 37 Average Request RTT 39
2016-10-26 06:48:46.739 Detail, Expected callbackId was received
2016-10-26 06:48:46.886 Detail, Node007, Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x07, 0x05, 0x85, 0x03, 0x01, 0x05, 0x00, 0x70
2016-10-26 06:48:46.886 Detail,
2016-10-26 06:48:46.902 Info, Node007, Response RTT 199 Average Response RTT 178
2016-10-26 06:48:46.902 Info, Node007, Received Association report from node 7, group 1, containing 0 associations
2016-10-26 06:48:46.902 Info, Node007, Querying associations for node 7 is complete.
2016-10-26 06:48:46.917 Info, Node007, Adding the controller to group 1 (LifeLine) of node 7
2016-10-26 06:48:46.917 Info, Node007, Association::Set - Adding node 1 to group 1 of node 7
2016-10-26 06:48:46.917 Detail, Node007, Queuing (Send) AssociationCmd_Set (Node=7): 0x01, 0x0b, 0x00, 0x13, 0x07, 0x04, 0x85, 0x01, 0x01, 0x01, 0x25, 0x3d, 0x78
2016-10-26 06:48:46.935 Info, Node007, Get Associations for group 1 of node 7
2016-10-26 06:48:46.937 Detail, Node007, Queuing (Send) AssociationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x25, 0x3e, 0x7f
2016-10-26 06:48:46.940 Detail, Node007, Expected reply and command class was received
2016-10-26 06:48:46.940 Detail, Node007, Message transaction complete
2016-10-26 06:48:46.940 Detail,
2016-10-26 06:48:46.940 Detail, Node007, Removing current message
2016-10-26 06:48:46.940 Detail, Node007, Notification: Group
2016-10-26 06:48:46.940 Detail,
2016-10-26 06:48:46.940 Info, Node007, Sending (Send) message (Callback ID=0x3d, Expected Reply=0x13) - AssociationCmd_Set (Node=7): 0x01, 0x0b, 0x00, 0x13, 0x07, 0x04, 0x85, 0x01, 0x01, 0x01, 0x25, 0x3d, 0x78
2016-10-26 06:48:46.956 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:46.956 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:51.043 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x3d, 0x01, 0x01, 0x99, 0x4f
2016-10-26 06:48:51.059 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x3d received (expected 0x3d)
2016-10-26 06:48:51.059 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:48:51.075 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 06:48:51.075 Detail, Node001, Expected callbackId was received
2016-10-26 06:48:51.075 Detail, Node001, Expected reply was received
2016-10-26 06:48:51.090 Detail, Node001, Message transaction complete
2016-10-26 06:48:51.090 Detail,
2016-10-26 06:48:51.090 Detail, Node007, Removing current message
2016-10-26 06:48:51.106 Detail,
2016-10-26 06:48:51.106 Info, Node007, Sending (Send) message (Callback ID=0x3e, Expected Reply=0x04) - AssociationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x25, 0x3e, 0x7f
2016-10-26 06:48:51.121 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:51.121 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:51.140 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x3e, 0x00, 0x00, 0x03, 0xd6
2016-10-26 06:48:51.142 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x3e received (expected 0x3e)
2016-10-26 06:48:51.144 Info, Node007, Request RTT 37 Average Request RTT 38
2016-10-26 06:48:51.144 Detail, Expected callbackId was received
2016-10-26 06:48:51.306 Detail, Node007, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x07, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0x75
2016-10-26 06:48:51.322 Detail,
2016-10-26 06:48:51.339 Info, Node007, Response RTT 233 Average Response RTT 205
2016-10-26 06:48:51.344 Info, Node007, Received Association report from node 7, group 1, containing 1 associations
2016-10-26 06:48:51.344 Info, Node007, The group contains:
2016-10-26 06:48:51.344 Info, Node007, Node 1
2016-10-26 06:48:51.344 Detail, Node007, Queuing (Controller) Delete All Return Routes
2016-10-26 06:48:51.359 Detail, Node007, Queuing (Controller) Assign Return Route
2016-10-26 06:48:51.359 Detail, Node007, Expected reply and command class was received
2016-10-26 06:48:51.359 Detail, Node007, Message transaction complete
2016-10-26 06:48:51.359 Detail,
2016-10-26 06:48:51.375 Detail, Node007, Removing current message
2016-10-26 06:48:51.375 Detail, Node007, Notification: Group
2016-10-26 06:48:51.375 Info, Deleting all return routes from node 7
2016-10-26 06:48:51.375 Detail, Node007, Queuing (Command) ControllerCommand_DeleteAllReturnRoutess (Node=7): 0x01, 0x05, 0x00, 0x47, 0x07, 0x3f, 0x85
2016-10-26 06:48:51.375 Detail, Notification: ControllerComand - Starting
2016-10-26 06:48:51.375 Detail,
2016-10-26 06:48:51.375 Info, Node007, Sending (Command) message (Callback ID=0x3f, Expected Reply=0x47) - ControllerCommand_DeleteAllReturnRoutess (Node=7): 0x01, 0x05, 0x00, 0x47, 0x07, 0x3f, 0x85
2016-10-26 06:48:51.391 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x47, 0x01, 0xbc
2016-10-26 06:48:51.391 Detail,
2016-10-26 06:48:51.391 Info, Node007, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE - command in progress
2016-10-26 06:48:51.391 Detail, Notification: ControllerCommand - InProgress
2016-10-26 06:48:51.574 Detail, Node007, Received: 0x01, 0x05, 0x00, 0x47, 0x3f, 0x01, 0x83
2016-10-26 06:48:51.574 Detail,
2016-10-26 06:48:51.590 Info, Node007, WARNING: ZW_DELETE_RETURN_ROUTE failed. No ACK received - device may be asleep.
2016-10-26 06:48:51.606 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 06:48:51.606 Detail, Node001, Expected callbackId was received
2016-10-26 06:48:51.621 Detail, Node001, Expected reply was received
2016-10-26 06:48:51.621 Detail, Node001, Message transaction complete
2016-10-26 06:48:51.621 Detail,
2016-10-26 06:48:51.637 Detail, Node007, Removing current message
2016-10-26 06:48:51.640 Detail, Notification: ControllerCommand - Failed
2016-10-26 06:48:51.643 Info, Assigning return route from node 7 to node 1
2016-10-26 06:48:51.644 Detail, Node007, Queuing (Command) ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x40, 0xf9
2016-10-26 06:48:51.644 Detail, Notification: ControllerComand - Starting
2016-10-26 06:48:51.644 Detail,
2016-10-26 06:48:51.644 Info, Node007, Sending (Command) message (Callback ID=0x40, Expected Reply=0x46) - ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x40, 0xf9
2016-10-26 06:48:51.659 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x46, 0x01, 0xbd
2016-10-26 06:48:51.659 Detail,
2016-10-26 06:48:51.659 Info, Node007, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command in progress
2016-10-26 06:48:51.659 Detail, Notification: ControllerCommand - InProgress
2016-10-26 06:48:52.122 Detail, Node007, Received: 0x01, 0x05, 0x00, 0x46, 0x40, 0x00, 0xfc
2016-10-26 06:48:52.144 Detail,
2016-10-26 06:48:52.144 Info, Node007, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE for node 7 - SUCCESS
2016-10-26 06:48:52.160 Detail, Expected callbackId was received
2016-10-26 06:48:52.175 Detail, Expected reply was received
2016-10-26 06:48:52.175 Detail, Message transaction complete
2016-10-26 06:48:52.191 Detail,
2016-10-26 06:48:52.191 Detail, Node007, Removing current message
2016-10-26 06:48:52.191 Detail, Notification: ControllerCommand - Completed
2016-10-26 06:48:52.191 Detail, Node007, Query Stage Complete (Associations)
2016-10-26 06:48:52.207 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2016-10-26 06:48:52.207 Detail, Node007, QueryStage_Neighbors
2016-10-26 06:48:52.207 Detail, Requesting routing info (neighbor list) for Node 7
2016-10-26 06:48:52.222 Detail, Node007, Queuing (Command) Get Routing Info (Node=7): 0x01, 0x07, 0x00, 0x80, 0x07, 0x00, 0x00, 0x03, 0x7c
2016-10-26 06:48:52.222 Detail, Node007, Queuing (Query) Query Stage Complete (Neighbors)
2016-10-26 06:48:52.222 Detail,
2016-10-26 06:48:52.222 Info, Node007, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=7): 0x01, 0x07, 0x00, 0x80, 0x07, 0x00, 0x00, 0x03, 0x7c
2016-10-26 06:48:52.222 Detail, Node007, 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
2016-10-26 06:48:52.222 Detail,
2016-10-26 06:48:52.239 Info, Node007, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2016-10-26 06:48:52.241 Info, Node007, Neighbors of this node are:
2016-10-26 06:48:52.243 Info, Node007, (none reported)
2016-10-26 06:48:52.244 Detail, Expected reply was received
2016-10-26 06:48:52.244 Detail, Message transaction complete
2016-10-26 06:48:52.244 Detail,
2016-10-26 06:48:52.244 Detail, Node007, Removing current message
2016-10-26 06:48:52.244 Detail, Node007, Query Stage Complete (Neighbors)
2016-10-26 06:48:52.244 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2016-10-26 06:48:52.244 Detail, Node007, QueryStage_Session
2016-10-26 06:48:52.244 Detail, Node007, Queuing (Query) PowerlevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x73, 0x02, 0x25, 0x41, 0xf5
2016-10-26 06:48:52.259 Detail, Node007, Queuing (Query) Query Stage Complete (Session)
2016-10-26 06:48:52.259 Detail,
2016-10-26 06:48:52.259 Info, Node007, Sending (Query) message (Callback ID=0x41, Expected Reply=0x04) - PowerlevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x73, 0x02, 0x25, 0x41, 0xf5
2016-10-26 06:48:52.259 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:52.275 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:52.291 Detail, Node007, Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.291 Detail,
2016-10-26 06:48:52.291 Info, Node007, Response RTT 31 Average Response RTT 118
2016-10-26 06:48:52.306 Info, Node007, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.306 Detail, Node007, Initial read of value
2016-10-26 06:48:52.306 Detail, Node007, Initial read of value
2016-10-26 06:48:52.306 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:52.306 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:52.341 Detail, Node007, Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.344 Detail,
2016-10-26 06:48:52.360 Info, Node007, Response RTT 100 Average Response RTT 109
2016-10-26 06:48:52.360 Info, Node007, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.360 Detail, Node007, Refreshed Value: old value=0, new value=0, type=list
2016-10-26 06:48:52.360 Detail, Node007, Changes to this value are not verified
2016-10-26 06:48:52.375 Detail, Node007, Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:48:52.375 Detail, Node007, Changes to this value are not verified
2016-10-26 06:48:52.375 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:52.375 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:52.476 Detail, Node007, Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.491 Detail,
2016-10-26 06:48:52.491 Info, Node007, Response RTT 231 Average Response RTT 170
2016-10-26 06:48:52.507 Info, Node007, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.507 Detail, Node007, Refreshed Value: old value=0, new value=0, type=list
2016-10-26 06:48:52.523 Detail, Node007, Changes to this value are not verified
2016-10-26 06:48:52.523 Detail, Node007, Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:48:52.523 Detail, Node007, Changes to this value are not verified
2016-10-26 06:48:52.540 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:52.542 Detail, Node007, Notification: ValueChanged
2016-10-26 06:48:56.379 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x41, 0x01, 0x01, 0x9c, 0x36
2016-10-26 06:48:56.395 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x41 received (expected 0x41)
2016-10-26 06:48:56.410 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:48:56.410 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 06:48:56.410 Detail, Node001, Expected callbackId was received
2016-10-26 06:49:02.263 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)


Dumping queued log messages

2016-10-26 06:48:52.259 4832
2016-10-26 06:48:52.259 4832 Sending (Query) message (Callback ID=0x41, Expected Reply=0x04) - PowerlevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x73, 0x02, 0x25, 0x41, 0xf5
2016-10-26 06:48:52.259 4832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:48:52.275 4832 ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:48:52.291 4832 Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.291 4832
2016-10-26 06:48:52.291 4832 Response RTT 31 Average Response RTT 118
2016-10-26 06:48:52.306 4832 Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.306 4832 Initial read of value
2016-10-26 06:48:52.306 4832 Initial read of value
2016-10-26 06:48:52.306 4832 Notification: ValueChanged
2016-10-26 06:48:52.306 4832 Notification: ValueChanged
2016-10-26 06:48:52.341 4832 Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.344 4832
2016-10-26 06:48:52.360 4832 Response RTT 100 Average Response RTT 109
2016-10-26 06:48:52.360 4832 Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.360 4832 Refreshed Value: old value=0, new value=0, type=list
2016-10-26 06:48:52.360 4832 Changes to this value are not verified
2016-10-26 06:48:52.375 4832 Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:48:52.375 4832 Changes to this value are not verified
2016-10-26 06:48:52.375 4832 Notification: ValueChanged
2016-10-26 06:48:52.375 4832 Notification: ValueChanged
2016-10-26 06:48:52.476 4832 Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x07, 0x04, 0x73, 0x03, 0x00, 0x00, 0x82
2016-10-26 06:48:52.491 4832
2016-10-26 06:48:52.491 4832 Response RTT 231 Average Response RTT 170
2016-10-26 06:48:52.507 4832 Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2016-10-26 06:48:52.507 4832 Refreshed Value: old value=0, new value=0, type=list
2016-10-26 06:48:52.523 4832 Changes to this value are not verified
2016-10-26 06:48:52.523 4832 Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:48:52.523 4832 Changes to this value are not verified
2016-10-26 06:48:52.540 4832 Notification: ValueChanged
2016-10-26 06:48:52.542 4832 Notification: ValueChanged
2016-10-26 06:48:56.379 4832 Received: 0x01, 0x07, 0x00, 0x13, 0x41, 0x01, 0x01, 0x9c, 0x36
2016-10-26 06:48:56.395 4832 ZW_SEND_DATA Request with callback ID 0x41 received (expected 0x41)
2016-10-26 06:48:56.410 4832 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:48:56.410 4832 WARNING: Device is not a sleeping node.
2016-10-26 06:48:56.410 4832 Expected callbackId was received
2016-10-26 06:49:02.263 4832 ERROR: Dropping command, expected response not received after 1 attempt(s)

End of queued log message dump


2016-10-26 06:49:02.417 Detail, Node007, Removing current message
2016-10-26 06:49:02.417 Detail, Node007, Notification: Notification - TimeOut
2016-10-26 06:49:02.417 Detail, Node007, Query Stage Complete (Session)
2016-10-26 06:49:02.417 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2016-10-26 06:49:02.417 Detail, Node007, QueryStage_Dynamic
2016-10-26 06:49:02.417 Detail, Node007, Queuing (Send) BasicCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x20, 0x02, 0x25, 0x42, 0xa5
2016-10-26 06:49:02.417 Detail, Node007, Queuing (Query) Query Stage Complete (Dynamic)
2016-10-26 06:49:02.432 Detail,
2016-10-26 06:49:02.432 Info, Node007, Sending (Send) message (Callback ID=0x42, Expected Reply=0x04) - BasicCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x20, 0x02, 0x25, 0x42, 0xa5
2016-10-26 06:49:02.432 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:49:02.432 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:49:02.548 Detail, Node007, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x07, 0x03, 0x20, 0x03, 0x00, 0xd5
2016-10-26 06:49:02.548 Detail,
2016-10-26 06:49:02.564 Info, Node007, Response RTT 131 Average Response RTT 150
2016-10-26 06:49:02.570 Info, Node007, Received Basic report from node 7: level=0
2016-10-26 06:49:02.570 Detail, Node007, Initial read of value
2016-10-26 06:49:02.586 Detail, Node007, Notification: ValueChanged
2016-10-26 06:49:02.919 Detail, Node007, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x07, 0x03, 0x20, 0x03, 0x00, 0xd5
2016-10-26 06:49:02.919 Detail,
2016-10-26 06:49:02.935 Info, Node007, Response RTT 502 Average Response RTT 326
2016-10-26 06:49:02.950 Info, Node007, Received Basic report from node 7: level=0
2016-10-26 06:49:02.950 Detail, Node007, Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:49:02.950 Detail, Node007, Changes to this value are not verified
2016-10-26 06:49:02.968 Detail, Node007, Notification: ValueChanged
2016-10-26 06:49:06.521 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x42, 0x01, 0x01, 0x99, 0x30
2016-10-26 06:49:06.537 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x42 received (expected 0x42)
2016-10-26 06:49:06.553 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:49:06.553 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 06:49:06.568 Detail, Node001, Expected callbackId was received
2016-10-26 06:49:12.442 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)


Dumping queued log messages

2016-10-26 06:49:02.432 4832
2016-10-26 06:49:02.432 4832 Sending (Send) message (Callback ID=0x42, Expected Reply=0x04) - BasicCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x20, 0x02, 0x25, 0x42, 0xa5
2016-10-26 06:49:02.432 4832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 06:49:02.432 4832 ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 06:49:02.548 4832 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x07, 0x03, 0x20, 0x03, 0x00, 0xd5
2016-10-26 06:49:02.548 4832
2016-10-26 06:49:02.564 4832 Response RTT 131 Average Response RTT 150
2016-10-26 06:49:02.570 4832 Received Basic report from node 7: level=0
2016-10-26 06:49:02.570 4832 Initial read of value
2016-10-26 06:49:02.586 4832 Notification: ValueChanged
2016-10-26 06:49:02.919 4832 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x07, 0x03, 0x20, 0x03, 0x00, 0xd5
2016-10-26 06:49:02.919 4832
2016-10-26 06:49:02.935 4832 Response RTT 502 Average Response RTT 326
2016-10-26 06:49:02.950 4832 Received Basic report from node 7: level=0
2016-10-26 06:49:02.950 4832 Refreshed Value: old value=0, new value=0, type=byte
2016-10-26 06:49:02.950 4832 Changes to this value are not verified
2016-10-26 06:49:02.968 4832 Notification: ValueChanged
2016-10-26 06:49:06.521 4832 Received: 0x01, 0x07, 0x00, 0x13, 0x42, 0x01, 0x01, 0x99, 0x30
2016-10-26 06:49:06.537 4832 ZW_SEND_DATA Request with callback ID 0x42 received (expected 0x42)
2016-10-26 06:49:06.553 4832 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 06:49:06.553 4832 WARNING: Device is not a sleeping node.
2016-10-26 06:49:06.568 4832 Expected callbackId was received
2016-10-26 06:49:12.442 4832 ERROR: Dropping command, expected response not received after 1 attempt(s)

End of queued log message dump


2016-10-26 06:49:12.542 Detail, Node007, Removing current message
2016-10-26 06:49:12.542 Detail, Node007, Notification: Notification - TimeOut
2016-10-26 06:49:12.558 Detail, Node007, Query Stage Complete (Dynamic)
2016-10-26 06:49:12.558 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2016-10-26 06:49:12.558 Detail, Node007, QueryStage_Configuration
2016-10-26 06:49:12.558 Detail, Node007, QueryStage_Complete
2016-10-26 06:49:12.558 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2016-10-26 06:49:12.558 Detail, Node007, Notification: NodeQueriesComplete

=======================================================================================

That's the last we hear from Node007, until I enable polling. Shortly afterwards, it is marked as dead.

=======================================================================================

2016-10-26 20:47:06.997 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2016-10-26 20:47:06.997 Detail, Node007, QueryStage_Configuration
2016-10-26 20:47:06.997 Detail, Node007, Queuing (Query) ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xc8, 0x25, 0x51, 0x2b
2016-10-26 20:47:06.997 Detail, Node007, Queuing (Query) ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xfe, 0x25, 0x52, 0x1e
2016-10-26 20:47:07.013 Detail, Node007, Queuing (Query) Query Stage Complete (Configuration)
2016-10-26 20:47:07.013 Detail,
2016-10-26 20:47:07.021 Info, Node007, Sending (Query) message (Callback ID=0x51, Expected Reply=0x04) - ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xc8, 0x25, 0x51, 0x2b
2016-10-26 20:47:07.031 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 20:47:07.034 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 20:47:11.148 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x51, 0x01, 0x01, 0x9d, 0x27
2016-10-26 20:47:11.148 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x51 received (expected 0x51)
2016-10-26 20:47:11.163 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 20:47:11.181 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 20:47:11.185 Detail, Node001, Expected callbackId was received
2016-10-26 20:47:17.037 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)


Dumping queued log messages

2016-10-26 20:47:06.997 4556 AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2016-10-26 20:47:06.997 4556 QueryStage_Configuration
2016-10-26 20:47:06.997 4556 Queuing (Query) ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xc8, 0x25, 0x51, 0x2b
2016-10-26 20:47:06.997 4556 Queuing (Query) ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xfe, 0x25, 0x52, 0x1e
2016-10-26 20:47:07.013 4556 Queuing (Query) Query Stage Complete (Configuration)
2016-10-26 20:47:07.013 4832
2016-10-26 20:47:07.021 4832 Sending (Query) message (Callback ID=0x51, Expected Reply=0x04) - ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xc8, 0x25, 0x51, 0x2b
2016-10-26 20:47:07.031 4832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 20:47:07.034 4832 ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 20:47:11.148 4832 Received: 0x01, 0x07, 0x00, 0x13, 0x51, 0x01, 0x01, 0x9d, 0x27
2016-10-26 20:47:11.148 4832 ZW_SEND_DATA Request with callback ID 0x51 received (expected 0x51)
2016-10-26 20:47:11.163 4832 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 20:47:11.181 4832 WARNING: Device is not a sleeping node.
2016-10-26 20:47:11.185 4832 Expected callbackId was received
2016-10-26 20:47:17.037 4832 ERROR: Dropping command, expected response not received after 1 attempt(s)

End of queued log message dump


2016-10-26 20:47:17.121 Detail, Node007, Removing current message
2016-10-26 20:47:17.121 Detail, Node007, Notification: Notification - TimeOut
2016-10-26 20:47:17.121 Detail,
2016-10-26 20:47:17.121 Info, Node007, Sending (Query) message (Callback ID=0x52, Expected Reply=0x04) - ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xfe, 0x25, 0x52, 0x1e
2016-10-26 20:47:17.137 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 20:47:17.137 Detail, Node007, ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 20:47:17.253 Detail, Node007, Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x07, 0x08, 0x70, 0x06, 0xfe, 0x02, 0x00, 0x00, 0x00, 0x00, 0x70
2016-10-26 20:47:17.268 Detail,
2016-10-26 20:47:17.268 Info, Node007, Response RTT 146 Average Response RTT 236
2016-10-26 20:47:17.291 Detail, Node007, Initial read of value
2016-10-26 20:47:17.291 Info, Node007, Received Configuration report: Parameter=254, Value=0
2016-10-26 20:47:17.306 Detail, Node007, Notification: ValueChanged
2016-10-26 20:47:17.306 Detail, Node007, Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x07, 0x08, 0x70, 0x06, 0xfe, 0x02, 0x00, 0x00, 0x00, 0x00, 0x70
2016-10-26 20:47:17.306 Detail,
2016-10-26 20:47:17.322 Info, Node007, Response RTT 200 Average Response RTT 218
2016-10-26 20:47:17.322 Detail, Node007, Refreshed Value: old value=0, new value=0, type=short
2016-10-26 20:47:17.322 Detail, Node007, Changes to this value are not verified
2016-10-26 20:47:17.337 Info, Node007, Received Configuration report: Parameter=254, Value=0
2016-10-26 20:47:17.337 Detail, Node007, Notification: ValueChanged
2016-10-26 20:47:21.294 Detail, Node007, Received: 0x01, 0x07, 0x00, 0x13, 0x52, 0x01, 0x01, 0xa1, 0x18
2016-10-26 20:47:21.310 Detail, Node007, ZW_SEND_DATA Request with callback ID 0x52 received (expected 0x52)
2016-10-26 20:47:21.325 Info, Node007, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 20:47:21.325 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 20:47:21.341 Detail, Node001, Expected callbackId was received
2016-10-26 20:47:27.122 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)


Dumping queued log messages

2016-10-26 20:47:17.121 4832
2016-10-26 20:47:17.121 4832 Sending (Query) message (Callback ID=0x52, Expected Reply=0x04) - ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0xfe, 0x25, 0x52, 0x1e
2016-10-26 20:47:17.137 4832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2016-10-26 20:47:17.137 4832 ZW_SEND_DATA delivered to Z-Wave stack
2016-10-26 20:47:17.253 4832 Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x07, 0x08, 0x70, 0x06, 0xfe, 0x02, 0x00, 0x00, 0x00, 0x00, 0x70
2016-10-26 20:47:17.268 4832
2016-10-26 20:47:17.268 4832 Response RTT 146 Average Response RTT 236
2016-10-26 20:47:17.291 4832 Initial read of value
2016-10-26 20:47:17.291 4832 Received Configuration report: Parameter=254, Value=0
2016-10-26 20:47:17.306 4832 Notification: ValueChanged
2016-10-26 20:47:17.306 4832 Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x07, 0x08, 0x70, 0x06, 0xfe, 0x02, 0x00, 0x00, 0x00, 0x00, 0x70
2016-10-26 20:47:17.306 4832
2016-10-26 20:47:17.322 4832 Response RTT 200 Average Response RTT 218
2016-10-26 20:47:17.322 4832 Refreshed Value: old value=0, new value=0, type=short
2016-10-26 20:47:17.322 4832 Changes to this value are not verified
2016-10-26 20:47:17.337 4832 Received Configuration report: Parameter=254, Value=0
2016-10-26 20:47:17.337 4832 Notification: ValueChanged
2016-10-26 20:47:21.294 4832 Received: 0x01, 0x07, 0x00, 0x13, 0x52, 0x01, 0x01, 0xa1, 0x18
2016-10-26 20:47:21.310 4832 ZW_SEND_DATA Request with callback ID 0x52 received (expected 0x52)
2016-10-26 20:47:21.325 4832 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2016-10-26 20:47:21.325 4832 WARNING: Device is not a sleeping node.
2016-10-26 20:47:21.341 4832 Expected callbackId was received
2016-10-26 20:47:27.122 4832 ERROR: Dropping command, expected response not received after 1 attempt(s)

End of queued log message dump


2016-10-26 20:47:27.181 Detail, Node007, Removing current message
2016-10-26 20:47:27.181 Detail, Node007, Notification: Notification - TimeOut
2016-10-26 20:47:27.181 Detail, Node007, Query Stage Complete (Configuration)
2016-10-26 20:47:27.181 Detail, Node007, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Complete live=1
2016-10-26 20:47:27.181 Detail, Node007, QueryStage_Complete
2016-10-26 20:47:27.181 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2016-10-26 20:47:27.196 Detail, Node007, Notification: NodeQueriesComplete
2016-10-26 20:47:27.196 Info, mgr, Manager::WriteConfig completed for driver with home ID of 0xfbd082c4
2016-10-26 20:47:29.759 Detail, Node007, Notification: NodeNaming
2016-10-26 20:49:11.642 Detail, Node001, Queuing (Controller) Request Node Neighbor Update
2016-10-26 20:49:11.646 Detail, Node003, Queuing (Controller) Request Node Neighbor Update
2016-10-26 20:49:11.649 Detail, Node003, Queuing (Controller) Delete All Return Routes
2016-10-26 20:49:11.652 Detail, Node005, Queuing (Controller) Request Node Neighbor Update
2016-10-26 20:49:11.655 Info, Requesting Neighbor Update for node 1
2016-10-26 20:49:11.657 Detail, Node005, Queuing (Controller) Delete All Return Routes
2016-10-26 20:49:11.660 Detail, Node005, Queuing (Controller) Assign Return Route
2016-10-26 20:49:11.662 Detail, Node007, Queuing (Controller) Request Node Neighbor Update
2016-10-26 20:49:11.665 Detail, Node007, Queuing (Controller) Delete All Return Routes
2016-10-26 20:49:11.667 Detail, Node007, Queuing (Controller) Assign Return Route
2016-10-26 20:49:11.670 Detail, Node001, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x53, 0xe0
2016-10-26 20:49:11.673 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:11.676 Detail,
2016-10-26 20:49:11.679 Info, Node001, Sending (Command) message (Callback ID=0x53, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x53, 0xe0
2016-10-26 20:49:11.711 Detail, Node001, Received: 0x01, 0x05, 0x00, 0x48, 0x53, 0x21, 0xc0
2016-10-26 20:49:11.714 Detail,
2016-10-26 20:49:11.716 Info, Node001, REQUEST_NEIGHBOR_UPDATE_STARTED
2016-10-26 20:49:11.718 Detail, Node033, Expected callbackId was received
2016-10-26 20:49:11.721 Detail, Node033, Expected reply was received
2016-10-26 20:49:11.723 Detail, Node033, Message transaction complete
2016-10-26 20:49:11.725 Detail,
2016-10-26 20:49:11.728 Detail, Node001, Removing current message
2016-10-26 20:49:11.730 Detail, Notification: ControllerCommand - InProgress
2016-10-26 20:49:20.641 Detail, Received: 0x01, 0x05, 0x00, 0x48, 0x53, 0x23, 0xc2
2016-10-26 20:49:20.644 Detail,
2016-10-26 20:49:20.647 Warning, WARNING: REQUEST_NEIGHBOR_UPDATE_FAILED
2016-10-26 20:49:20.649 Detail, Notification: ControllerCommand - Failed
2016-10-26 20:49:20.652 Info, Requesting Neighbor Update for node 3
2016-10-26 20:49:20.654 Detail,
2016-10-26 20:49:20.656 Detail, Node003, Queuing (Controller) Request Node Neighbor Update
2016-10-26 20:49:20.658 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.658 Detail, Notification: ControllerCommand - Sleeping
2016-10-26 20:49:20.658 Info, Deleting all return routes from node 3
2016-10-26 20:49:20.658 Detail,
2016-10-26 20:49:20.658 Detail, Node003, Queuing (Controller) Delete All Return Routes
2016-10-26 20:49:20.658 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.658 Detail, Notification: ControllerCommand - Sleeping
2016-10-26 20:49:20.658 Info, Requesting Neighbor Update for node 5
2016-10-26 20:49:20.675 Detail, Node005, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=5): 0x01, 0x05, 0x00, 0x48, 0x05, 0x56, 0xe1
2016-10-26 20:49:20.675 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.675 Error, Node005, ERROR: Dropping command because node is presumed dead


Dumping queued log messages

2016-10-26 20:49:20.658 4832 Requesting Neighbor Update for node 5
2016-10-26 20:49:20.675 4832 Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=5): 0x01, 0x05, 0x00, 0x48, 0x05, 0x56, 0xe1
2016-10-26 20:49:20.675 4832 Notification: ControllerComand - Starting
2016-10-26 20:49:20.675 4832 ERROR: Dropping command because node is presumed dead

End of queued log message dump


2016-10-26 20:49:20.690 Detail, Node005, Removing current message
2016-10-26 20:49:20.690 Detail, Notification: ControllerCommand - Error - Failed
2016-10-26 20:49:20.690 Info, Deleting all return routes from node 5
2016-10-26 20:49:20.690 Detail, Node005, Queuing (Command) ControllerCommand_DeleteAllReturnRoutess (Node=5): 0x01, 0x05, 0x00, 0x47, 0x05, 0x57, 0xef
2016-10-26 20:49:20.690 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.690 Error, Node005, ERROR: Dropping command because node is presumed dead


Dumping queued log messages

2016-10-26 20:49:20.690 4832 Deleting all return routes from node 5
2016-10-26 20:49:20.690 4832 Queuing (Command) ControllerCommand_DeleteAllReturnRoutess (Node=5): 0x01, 0x05, 0x00, 0x47, 0x05, 0x57, 0xef
2016-10-26 20:49:20.690 4832 Notification: ControllerComand - Starting
2016-10-26 20:49:20.690 4832 ERROR: Dropping command because node is presumed dead

End of queued log message dump


2016-10-26 20:49:20.706 Detail, Node005, Removing current message
2016-10-26 20:49:20.706 Detail, Notification: ControllerCommand - Error - Failed
2016-10-26 20:49:20.706 Info, Assigning return route from node 5 to node 1
2016-10-26 20:49:20.721 Detail, Node005, Queuing (Command) ControllerCommand_AssignReturnRoute (Node=5): 0x01, 0x06, 0x00, 0x46, 0x05, 0x01, 0x58, 0xe3
2016-10-26 20:49:20.721 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.721 Error, Node005, ERROR: Dropping command because node is presumed dead


Dumping queued log messages

2016-10-26 20:49:20.706 4832 Assigning return route from node 5 to node 1
2016-10-26 20:49:20.721 4832 Queuing (Command) ControllerCommand_AssignReturnRoute (Node=5): 0x01, 0x06, 0x00, 0x46, 0x05, 0x01, 0x58, 0xe3
2016-10-26 20:49:20.721 4832 Notification: ControllerComand - Starting
2016-10-26 20:49:20.721 4832 ERROR: Dropping command because node is presumed dead

End of queued log message dump


2016-10-26 20:49:20.737 Detail, Node005, Removing current message
2016-10-26 20:49:20.737 Detail, Notification: ControllerCommand - Error - Failed
2016-10-26 20:49:20.737 Info, Requesting Neighbor Update for node 7
2016-10-26 20:49:20.737 Detail, Node007, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=7): 0x01, 0x05, 0x00, 0x48, 0x07, 0x59, 0xec
2016-10-26 20:49:20.737 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:20.737 Detail,
2016-10-26 20:49:20.737 Info, Node007, Sending (Command) message (Callback ID=0x59, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=7): 0x01, 0x05, 0x00, 0x48, 0x07, 0x59, 0xec
2016-10-26 20:49:20.775 Detail, Node007, Received: 0x01, 0x05, 0x00, 0x48, 0x59, 0x21, 0xca
2016-10-26 20:49:20.775 Detail,
2016-10-26 20:49:20.790 Info, Node007, REQUEST_NEIGHBOR_UPDATE_STARTED
2016-10-26 20:49:20.790 Detail, Node033, Expected callbackId was received
2016-10-26 20:49:20.806 Detail, Node033, Expected reply was received
2016-10-26 20:49:20.806 Detail, Node033, Message transaction complete
2016-10-26 20:49:20.806 Detail,
2016-10-26 20:49:20.822 Detail, Node007, Removing current message
2016-10-26 20:49:20.822 Detail, Notification: ControllerCommand - InProgress
2016-10-26 20:49:28.938 Detail, Received: 0x01, 0x05, 0x00, 0x48, 0x59, 0x23, 0xc8
2016-10-26 20:49:28.941 Detail,
2016-10-26 20:49:28.944 Warning, WARNING: REQUEST_NEIGHBOR_UPDATE_FAILED
2016-10-26 20:49:28.946 Detail, Notification: ControllerCommand - Failed
2016-10-26 20:49:28.948 Info, Deleting all return routes from node 7
2016-10-26 20:49:28.949 Detail, Node007, Queuing (Command) ControllerCommand_DeleteAllReturnRoutess (Node=7): 0x01, 0x05, 0x00, 0x47, 0x07, 0x5a, 0xe0
2016-10-26 20:49:28.949 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:28.949 Detail,
2016-10-26 20:49:28.949 Info, Node007, Sending (Command) message (Callback ID=0x5a, Expected Reply=0x47) - ControllerCommand_DeleteAllReturnRoutess (Node=7): 0x01, 0x05, 0x00, 0x47, 0x07, 0x5a, 0xe0
2016-10-26 20:49:28.965 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x47, 0x01, 0xbc
2016-10-26 20:49:28.965 Detail,
2016-10-26 20:49:28.965 Info, Node007, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE - command in progress
2016-10-26 20:49:28.965 Detail, Notification: ControllerCommand - InProgress
2016-10-26 20:49:29.097 Detail, Node007, Received: 0x01, 0x05, 0x00, 0x47, 0x5a, 0x01, 0xe6
2016-10-26 20:49:29.112 Detail,
2016-10-26 20:49:29.112 Info, Node007, WARNING: ZW_DELETE_RETURN_ROUTE failed. No ACK received - device may be asleep.
2016-10-26 20:49:29.128 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 20:49:29.128 Detail, Node001, Expected callbackId was received
2016-10-26 20:49:29.128 Detail, Node001, Expected reply was received
2016-10-26 20:49:29.128 Detail, Node001, Message transaction complete
2016-10-26 20:49:29.145 Detail,
2016-10-26 20:49:29.147 Detail, Node007, Removing current message
2016-10-26 20:49:29.149 Detail, Notification: ControllerCommand - Failed
2016-10-26 20:49:29.150 Info, Assigning return route from node 7 to node 1
2016-10-26 20:49:29.150 Detail, Node007, Queuing (Command) ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x5b, 0xe2
2016-10-26 20:49:29.150 Detail, Notification: ControllerComand - Starting
2016-10-26 20:49:29.150 Detail,
2016-10-26 20:49:29.150 Info, Node007, Sending (Command) message (Callback ID=0x5b, Expected Reply=0x46) - ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x5b, 0xe2
2016-10-26 20:49:29.166 Detail, Node007, Received: 0x01, 0x04, 0x01, 0x46, 0x01, 0xbd
2016-10-26 20:49:29.166 Detail,
2016-10-26 20:49:29.166 Info, Node007, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command in progress
2016-10-26 20:49:29.166 Detail, Notification: ControllerCommand - InProgress
2016-10-26 20:49:29.266 Detail, Node007, Received: 0x01, 0x05, 0x00, 0x46, 0x5b, 0x01, 0xe6
2016-10-26 20:49:29.281 Detail,
2016-10-26 20:49:29.297 Info, Node007, WARNING: ZW_ASSIGN_RETURN_ROUTE failed. No ACK received - device may be asleep.
2016-10-26 20:49:29.297 Warning, Node007, WARNING: Device is not a sleeping node.
2016-10-26 20:49:29.297 Error, Node007, ERROR: node presumed dead


Dumping queued log messages

2016-10-26 20:49:29.150 4832 Assigning return route from node 7 to node 1
2016-10-26 20:49:29.150 4832 Queuing (Command) ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x5b, 0xe2
2016-10-26 20:49:29.150 4832 Notification: ControllerComand - Starting
2016-10-26 20:49:29.150 4832
2016-10-26 20:49:29.150 4832 Sending (Command) message (Callback ID=0x5b, Expected Reply=0x46) - ControllerCommand_AssignReturnRoute (Node=7): 0x01, 0x06, 0x00, 0x46, 0x07, 0x01, 0x5b, 0xe2
2016-10-26 20:49:29.166 4832 Received: 0x01, 0x04, 0x01, 0x46, 0x01, 0xbd
2016-10-26 20:49:29.166 4832
2016-10-26 20:49:29.166 4832 Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command in progress
2016-10-26 20:49:29.166 4832 Notification: ControllerCommand - InProgress
2016-10-26 20:49:29.266 4832 Received: 0x01, 0x05, 0x00, 0x46, 0x5b, 0x01, 0xe6
2016-10-26 20:49:29.281 4832
2016-10-26 20:49:29.297 4832 WARNING: ZW_ASSIGN_RETURN_ROUTE failed. No ACK received - device may be asleep.
2016-10-26 20:49:29.297 4832 WARNING: Device is not a sleeping node.
2016-10-26 20:49:29.297 4832 ERROR: node presumed dead

End of queued log message dump
Top
Post Reply

Return to “Other questions and discussions”

Who is online

Users browsing this forum: No registered users and 1 guest