Hi
I have HA 0.45.1 running on Linux Ubuntu 16.04.2 LTS. I have a USB Zwave key that looks to be recognised correctly in HA (just in case lsusb listing gives me these infos for Zwave dongle:
Bus 003 Device 007: ID 0658:0200 Sigma Designs, Inc.
I have a little eye sensor from Fibaro. I removed battery insulation to start the Fiabro sensor. I clicked on Add Node in HA (nothing visual happens in HA out of button changing color and a short text popup). I triple click the button on the Fibaro to put it in pairing mode (confirmed by the eye going blue) but on HA nothing else happens out of lot of errors in ZWave (see below). Does it sound logical for someone ? and if so what’s problem ?
For now it’s the only Zwave device I have but should have few mores tomorrow !!
Thanks for help
Vincèn
2017-05-26 11:23:51.033 Always, OpenZwave Version 1.4.2508 Starting Up
2017-05-26 11:23:52.909 Info, Setting Up Provided Network Key for Secure Communications
2017-05-26 11:23:52.909 Warning, Failed - Network Key Not Set
2017-05-26 11:23:52.909 Info, mgr, Added driver for controller /dev/ttyACM0
2017-05-26 11:23:52.909 Info, Opening controller /dev/ttyACM0
2017-05-26 11:23:52.909 Info, Trying to open serial port /dev/ttyACM0 (attempt 1)
2017-05-26 11:23:52.909 Info, Serial port /dev/ttyACM0 opened (attempt 1)
2017-05-26 11:23:52.910 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2017-05-26 11:23:52.910 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2017-05-26 11:23:52.910 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2017-05-26 11:23:52.910 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2017-05-26 11:23:52.910 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2017-05-26 11:23:52.910 Detail,
2017-05-26 11:23:52.910 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2017-05-26 11:23:52.912 Detail, contrlr, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97
2017-05-26 11:23:52.912 Detail,
2017-05-26 11:23:52.912 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2017-05-26 11:23:52.912 Info, contrlr, Static Controller library, version Z-Wave 4.05
2017-05-26 11:23:52.912 Detail, Node045, Expected reply was received
2017-05-26 11:23:52.912 Detail, Node045, Message transaction complete
2017-05-26 11:23:52.912 Detail,
2017-05-26 11:23:52.912 Detail, contrlr, Removing current message
2017-05-26 11:23:52.912 Detail,
2017-05-26 11:23:52.912 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2017-05-26 11:23:52.914 Detail, contrlr, Received: 0x01, 0x08, 0x01, 0x20, 0xc9, 0x67, 0x3d, 0xf8, 0x01, 0xbc
2017-05-26 11:23:52.914 Detail,
2017-05-26 11:23:52.914 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xc9673df8. Our node ID = 1
2017-05-26 11:23:52.914 Detail, Node103, Expected reply was received
2017-05-26 11:23:52.914 Detail, Node103, Message transaction complete
2017-05-26 11:23:52.914 Detail,
2017-05-26 11:23:52.914 Detail, contrlr, Removing current message
2017-05-26 11:23:52.914 Detail,
2017-05-26 11:23:52.914 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2017-05-26 11:23:52.915 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7
2017-05-26 11:23:52.915 Detail,
2017-05-26 11:23:52.915 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2017-05-26 11:23:52.915 Info, contrlr, There is no SUC ID Server (SIS) in this network.
2017-05-26 11:23:52.915 Info, contrlr, The PC controller is a primary controller.
2017-05-26 11:23:52.915 Detail, Node215, Expected reply was received
2017-05-26 11:23:52.915 Detail, Node215, Message transaction complete
2017-05-26 11:23:52.915 Detail,
2017-05-26 11:23:52.915 Detail, contrlr, Removing current message
2017-05-26 11:23:52.915 Detail,
2017-05-26 11:23:52.915 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2017-05-26 11:23:52.919 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-05-26 11:23:52.919 Detail,
2017-05-26 11:23:52.919 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2017-05-26 11:23:52.919 Info, contrlr, Serial API Version: 5.5
2017-05-26 11:23:52.919 Info, contrlr, Manufacturer ID: 0x0115
2017-05-26 11:23:52.919 Info, contrlr, Product Type: 0x0400
2017-05-26 11:23:52.919 Info, contrlr, Product ID: 0x0001
2017-05-26 11:23:52.919 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2017-05-26 11:23:52.919 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2017-05-26 11:23:52.919 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2017-05-26 11:23:52.919 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2017-05-26 11:23:52.919 Detail, Node005, Expected reply was received
2017-05-26 11:23:52.919 Detail, Node005, Message transaction complete
2017-05-26 11:23:52.919 Detail,
2017-05-26 11:23:52.919 Detail, contrlr, Removing current message
2017-05-26 11:23:52.919 Detail,
2017-05-26 11:23:52.919 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2017-05-26 11:23:52.921 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2017-05-26 11:23:52.921 Detail,
2017-05-26 11:23:52.921 Info, contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0
2017-05-26 11:23:52.921 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
2017-05-26 11:23:52.921 Detail, Node172, Expected reply was received
2017-05-26 11:23:52.921 Detail, Node172, Message transaction complete
2017-05-26 11:23:52.921 Detail,
2017-05-26 11:23:52.921 Detail, contrlr, Removing current message
2017-05-26 11:23:52.921 Detail,
2017-05-26 11:23:52.921 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2017-05-26 11:23:52.976 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x88, 0xd8, 0x82, 0xa9, 0x7a, 0x70, 0x09, 0xbc, 0xe1, 0x7a, 0x23, 0x43, 0x45, 0x8a, 0xc9, 0xd3, 0xeb, 0xe1, 0xde, 0xff, 0xfc, 0x8c, 0x32, 0x27, 0x45, 0x04, 0x84, 0xe9, 0xce, 0x1c, 0x04, 0x3d, 0x85
2017-05-26 11:23:52.976 Detail,
2017-05-26 11:23:52.976 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2017-05-26 11:23:52.976 Detail, Node032, Expected reply was received
2017-05-26 11:23:52.976 Detail, Node032, Message transaction complete
2017-05-26 11:23:52.976 Detail,
2017-05-26 11:23:52.976 Detail, contrlr, Removing current message
2017-05-26 11:23:52.976 Detail,
2017-05-26 11:23:52.976 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2017-05-26 11:23:53.075 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x01, 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, 0xc5
2017-05-26 11:23:53.075 Detail,
2017-05-26 11:23:53.075 Info, mgr, Driver with Home ID of 0xc9673df8 is now ready.
2017-05-26 11:23:53.075 Info,
2017-05-26 11:23:53.076 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2017-05-26 11:23:53.076 Info, contrlr, Node 001 - Known
2017-05-26 11:23:53.076 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2017-05-26 11:23:53.076 Detail, Node001, QueryStage_CacheLoad
2017-05-26 11:23:53.076 Info, Node001, Node Identity Codes: 0115:0400:0001
2017-05-26 11:23:53.076 Detail, Node001, QueryStage_Associations
2017-05-26 11:23:53.076 Detail, Node001, QueryStage_Neighbors
2017-05-26 11:23:53.076 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2017-05-26 11:23:53.076 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2017-05-26 11:23:53.076 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2017-05-26 11:23:53.076 Detail, Expected reply was received
2017-05-26 11:23:53.076 Detail, Message transaction complete
2017-05-26 11:23:53.076 Detail,
2017-05-26 11:23:53.076 Detail, contrlr, Removing current message
2017-05-26 11:23:53.076 Detail, Node001, Notification: DriverReady
2017-05-26 11:23:53.077 Detail, Node001, Notification: NodeAdded
2017-05-26 11:23:53.079 Detail, Node001, Notification: NodeProtocolInfo
2017-05-26 11:23:53.080 Detail, Node001, Notification: EssentialNodeQueriesComplete
2017-05-26 11:23:53.080 Detail, Node001, Notification: ValueAdded
2017-05-26 11:23:53.080 Detail, Node001, Notification: NodeNaming
2017-05-26 11:23:53.080 Detail,
2017-05-26 11:23:53.080 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-05-26 11:23:53.086 Detail, contrlr, Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2017-05-26 11:23:53.086 Detail,
2017-05-26 11:23:53.086 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2017-05-26 11:23:53.086 Detail, Node015, Expected reply was received
2017-05-26 11:23:53.086 Detail, Node015, Message transaction complete
2017-05-26 11:23:53.086 Detail,
2017-05-26 11:23:53.086 Detail, contrlr, Removing current message
2017-05-26 11:23:53.086 Detail,
2017-05-26 11:23:53.086 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-05-26 11:23:53.087 Detail, contrlr, Removing current message
2017-05-26 11:23:53.087 Detail,
2017-05-26 11:23:53.087 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-05-26 11:23:53.093 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-05-26 11:23:53.093 Detail,
2017-05-26 11:23:53.093 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2017-05-26 11:23:53.093 Info, Node001, Neighbors of this node are:
2017-05-26 11:23:53.093 Info, Node001, (none reported)
2017-05-26 11:23:53.093 Detail, Expected reply was received
2017-05-26 11:23:53.093 Detail, Message transaction complete
2017-05-26 11:23:53.093 Detail,
2017-05-26 11:23:53.093 Detail, Node001, Removing current message
2017-05-26 11:23:53.093 Detail, Node001, Query Stage Complete (Neighbors)
2017-05-26 11:23:53.093 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2017-05-26 11:23:53.093 Detail, Node001, QueryStage_Session
2017-05-26 11:23:53.093 Detail, Node001, QueryStage_Dynamic
2017-05-26 11:23:53.093 Detail, Node001, QueryStage_Configuration
2017-05-26 11:23:53.093 Detail, Node001, QueryStage_Complete
2017-05-26 11:23:53.093 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2017-05-26 11:23:53.093 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2017-05-26 11:23:53.093 Info, Node query processing complete.
2017-05-26 11:23:53.093 Detail, Node001, Notification: NodeQueriesComplete
2017-05-26 11:23:53.093 Detail, contrlr, Notification: AllNodesQueried
2017-05-26 11:48:35.737 Detail, Queuing (Controller) Add Device
2017-05-26 11:48:35.737 Info, Add Device
2017-05-26 11:48:35.737 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2017-05-26 11:48:35.737 Detail, Notification: ControllerCommand - Starting
2017-05-26 11:48:35.737 Detail,
2017-05-26 11:48:35.737 Info, contrlr, Sending (Command) message (Callback ID=0x0a, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0a, 0x7b
2017-05-26 11:48:35.740 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x0a, 0x01, 0x00, 0x00, 0xb9
2017-05-26 11:48:35.740 Detail,
2017-05-26 11:48:35.740 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2017-05-26 11:48:35.740 Info, contrlr, ADD_NODE_STATUS_LEARN_READY
2017-05-26 11:48:35.740 Detail, Node001, Expected callbackId was received
2017-05-26 11:48:35.740 Detail, Node001, Expected reply was received
2017-05-26 11:48:35.740 Detail, Node001, Message transaction complete
2017-05-26 11:48:35.740 Detail,
2017-05-26 11:48:35.740 Detail, contrlr, Removing current message
2017-05-26 11:48:35.740 Detail, Notification: ControllerCommand - Waiting
2017-05-26 11:49:09.134 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2017-05-26 11:49:09.134 Detail, Node001, QueryStage_Probe
2017-05-26 11:49:09.134 Detail, Node001, QueryStage_WakeUp
2017-05-26 11:49:09.134 Detail, Node001, QueryStage_ManufacturerSpecific1
2017-05-26 11:49:09.134 Detail, Node001, Load Controller Manufacturer Specific Config
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_NodePlusInfo
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_SecurityReport
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_ManufacturerSpecific2
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_Versions
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_Instances
2017-05-26 11:49:09.142 Info, Node001, Essential node queries are complete
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_Static
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_Associations
2017-05-26 11:49:09.142 Detail, Node001, QueryStage_Neighbors
2017-05-26 11:49:09.142 Detail, Requesting routing info (neighbor list) for Node 1
2017-05-26 11:49:09.142 Detail, Node001, Notification: EssentialNodeQueriesComplete
2017-05-26 11:49:09.142 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2017-05-26 11:49:09.142 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2017-05-26 11:49:09.142 Detail,
2017-05-26 11:49:09.142 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-05-26 11:49:09.146 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-05-26 11:49:09.146 Detail,
2017-05-26 11:49:09.146 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2017-05-26 11:49:09.146 Info, Node001, Neighbors of this node are:
2017-05-26 11:49:09.146 Info, Node001, (none reported)
2017-05-26 11:49:09.146 Detail, Expected reply was received
2017-05-26 11:49:09.146 Detail, Message transaction complete
2017-05-26 11:49:09.146 Detail,
2017-05-26 11:49:09.146 Detail, Node001, Removing current message
2017-05-26 11:49:12.773 Detail, Node001, Notification: NodeNaming
2017-05-26 11:53:26.811 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-05-26 11:53:26.811 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-05-26 11:55:02.409 Detail, Node001, Queuing (Controller) Request Node Neighbor Update
2017-05-26 12:03:19.662 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Probe
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_WakeUp
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_ManufacturerSpecific1
2017-05-26 12:03:19.662 Detail, Node001, Load Controller Manufacturer Specific Config
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_NodePlusInfo
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_SecurityReport
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_ManufacturerSpecific2
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Versions
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Instances
2017-05-26 12:03:19.662 Info, Node001, Essential node queries are complete
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Static
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Associations
2017-05-26 12:03:19.662 Detail, Node001, QueryStage_Neighbors
2017-05-26 12:03:19.662 Detail, Requesting routing info (neighbor list) for Node 1
2017-05-26 12:03:19.662 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2017-05-26 12:03:19.662 Detail, Node001, Notification: EssentialNodeQueriesComplete
2017-05-26 12:03:19.662 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2017-05-26 12:03:19.662 Detail,
2017-05-26 12:03:19.662 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-05-26 12:03:19.666 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-05-26 12:03:19.667 Detail,
2017-05-26 12:03:19.667 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2017-05-26 12:03:19.667 Info, Node001, Neighbors of this node are:
2017-05-26 12:03:19.667 Info, Node001, (none reported)
2017-05-26 12:03:19.667 Detail, Expected reply was received
2017-05-26 12:03:19.667 Detail, Message transaction complete
2017-05-26 12:03:19.667 Detail,
2017-05-26 12:03:19.667 Detail, Node001, Removing current message
2017-05-26 12:03:36.307 Info, Cancel Add Node
2017-05-26 12:03:36.307 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2017-05-26 12:03:36.307 Detail, Notification: ControllerCommand - Canceled
2017-05-26 12:03:36.307 Detail,
2017-05-26 12:03:36.307 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2017-05-26 12:03:39.939 Info, Soft-resetting the Z-Wave controller chip
2017-05-26 12:03:39.939 Detail, contrlr, Queuing (Command) Soft-resetting the Z-Wave controller chip: 0x01, 0x03, 0x00, 0x08, 0xf4
2017-05-26 12:03:46.308 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:03:46.308 Detail, contrlr, Removing current message
2017-05-26 12:03:46.308 Detail, contrlr, Notification: Notification - TimeOut
2017-05-26 12:03:46.308 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:03:46.308 Detail,
2017-05-26 12:03:46.308 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - Soft-resetting the Z-Wave controller chip: 0x01, 0x03, 0x00, 0x08, 0xf4
2017-05-26 12:03:46.309 Detail, contrlr, Removing current message
2017-05-26 12:03:46.309 Info, Requesting Neighbor Update for node 1
2017-05-26 12:03:46.309 Detail, Node001, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x0b, 0xb8
2017-05-26 12:03:46.309 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:03:46.309 Detail,
2017-05-26 12:03:46.309 Info, Node001, Sending (Command) message (Callback ID=0x0b, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x0b, 0xb8
2017-05-26 12:03:47.309 Error, Node001, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:03:47.309 Detail, Node001, Removing current message
2017-05-26 12:03:47.309 Detail, Node001, Notification: Notification - TimeOut
2017-05-26 12:03:47.310 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:03:47.310 Detail, Node001, Query Stage Complete (Neighbors)
2017-05-26 12:03:47.310 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2017-05-26 12:03:47.310 Detail, Node001, QueryStage_Session
2017-05-26 12:03:47.310 Detail, Node001, QueryStage_Dynamic
2017-05-26 12:03:47.310 Detail, Node001, QueryStage_Configuration
2017-05-26 12:03:47.310 Detail, Node001, QueryStage_Complete
2017-05-26 12:03:47.310 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2017-05-26 12:03:47.310 Detail, Node001, Notification: NodeQueriesComplete
2017-05-26 12:03:47.311 Detail, Node001, Query Stage Complete (Neighbors)
2017-05-26 12:03:47.311 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Complete live=1
2017-05-26 12:03:47.311 Detail, Node001, QueryStage_Complete
2017-05-26 12:03:47.311 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2017-05-26 12:03:47.311 Detail, Node001, Notification: NodeQueriesComplete
2017-05-26 12:04:58.569 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=ProtocolInfo live=1
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Probe
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_WakeUp
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_ManufacturerSpecific1
2017-05-26 12:04:58.569 Detail, Node001, Load Controller Manufacturer Specific Config
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_NodePlusInfo
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_SecurityReport
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_ManufacturerSpecific2
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Versions
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Instances
2017-05-26 12:04:58.569 Info, Node001, Essential node queries are complete
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Static
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Associations
2017-05-26 12:04:58.569 Detail, Node001, QueryStage_Neighbors
2017-05-26 12:04:58.569 Detail, Requesting routing info (neighbor list) for Node 1
2017-05-26 12:04:58.569 Detail, Node001, Notification: EssentialNodeQueriesComplete
2017-05-26 12:04:58.569 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2017-05-26 12:04:58.569 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2017-05-26 12:04:58.570 Detail,
2017-05-26 12:04:58.570 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-05-26 12:04:59.570 Error, Node001, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:04:59.570 Detail, Node001, Removing current message
2017-05-26 12:04:59.570 Detail, Node001, Notification: Notification - TimeOut
2017-05-26 12:04:59.570 Detail, Node001, Query Stage Complete (Neighbors)
2017-05-26 12:04:59.570 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2017-05-26 12:04:59.570 Detail, Node001, QueryStage_Session
2017-05-26 12:04:59.570 Detail, Node001, QueryStage_Dynamic
2017-05-26 12:04:59.570 Detail, Node001, QueryStage_Configuration
2017-05-26 12:04:59.570 Detail, Node001, QueryStage_Complete
2017-05-26 12:04:59.570 Warning, CheckCompletedNodeQueries m_allNodesQueried=1 m_awakeNodesQueried=1
2017-05-26 12:04:59.570 Detail, Node001, Notification: NodeQueriesComplete
2017-05-26 12:13:35.730 Detail, Queuing (Controller) Remove Device
2017-05-26 12:13:35.730 Info, Remove Device
2017-05-26 12:13:35.730 Detail, contrlr, Queuing (Command) ControllerCommand_RemoveDevice: 0x01, 0x05, 0x00, 0x4b, 0x81, 0x0c, 0x3c
2017-05-26 12:13:35.730 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:13:35.731 Detail,
2017-05-26 12:13:35.731 Info, contrlr, Sending (Command) message (Callback ID=0x0c, Expected Reply=0x4b) - ControllerCommand_RemoveDevice: 0x01, 0x05, 0x00, 0x4b, 0x81, 0x0c, 0x3c
2017-05-26 12:13:36.731 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:13:36.731 Detail, contrlr, Removing current message
2017-05-26 12:13:36.732 Detail, contrlr, Notification: Notification - TimeOut
2017-05-26 12:13:36.732 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:14:11.443 Detail, Queuing (Controller) Add Device
2017-05-26 12:14:11.443 Info, Add Device
2017-05-26 12:14:11.443 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0d, 0x7c
2017-05-26 12:14:11.443 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:14:11.445 Detail,
2017-05-26 12:14:11.445 Info, contrlr, Sending (Command) message (Callback ID=0x0d, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0d, 0x7c
2017-05-26 12:14:12.445 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:14:12.445 Detail, contrlr, Removing current message
2017-05-26 12:14:12.445 Detail, contrlr, Notification: Notification - TimeOut
2017-05-26 12:14:12.445 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:16:41.770 Detail, Queuing (Controller) Add Device
2017-05-26 12:16:41.770 Info, Add Device
2017-05-26 12:16:41.770 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0e, 0x7f
2017-05-26 12:16:41.770 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:16:41.770 Detail,
2017-05-26 12:16:41.770 Info, contrlr, Sending (Command) message (Callback ID=0x0e, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0e, 0x7f
2017-05-26 12:16:42.770 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:16:42.770 Detail, contrlr, Removing current message
2017-05-26 12:16:42.770 Detail, contrlr, Notification: Notification - TimeOut
2017-05-26 12:16:42.770 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:17:35.843 Detail, Queuing (Controller) Add Device
2017-05-26 12:17:35.844 Info, Add Device
2017-05-26 12:17:35.844 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0f, 0x7e
2017-05-26 12:17:35.844 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:17:35.845 Detail,
2017-05-26 12:17:35.845 Info, contrlr, Sending (Command) message (Callback ID=0x0f, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x0f, 0x7e
2017-05-26 12:17:36.845 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:17:36.845 Detail, contrlr, Removing current message
2017-05-26 12:17:36.845 Detail, contrlr, Notification: Notification - TimeOut
2017-05-26 12:17:36.845 Detail, Notification: ControllerCommand - Error - Failed
2017-05-26 12:20:17.530 Detail, Node001, Queuing (Controller) Request Node Neighbor Update
2017-05-26 12:20:17.530 Info, Requesting Neighbor Update for node 1
2017-05-26 12:20:17.530 Detail, Node001, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x10, 0xa3
2017-05-26 12:20:17.530 Detail, Notification: ControllerCommand - Starting
2017-05-26 12:20:17.530 Detail,
2017-05-26 12:20:17.530 Info, Node001, Sending (Command) message (Callback ID=0x10, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x10, 0xa3
2017-05-26 12:20:18.531 Error, Node001, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-05-26 12:20:18.531 Detail, Node001, Removing current message
2017-05-26 12:20:18.531 Detail, Node001, Notification: Notification - TimeOut
2017-05-26 12:20:18.531 Detail, Notification: ControllerCommand - Error - Failed