2018-09-01 14:07:52.498 Info, Reset controller and erase all node information 2018-09-01 14:07:52.499 Detail, contrlr, Queuing (Command) Reset controller and erase all node information: 0x01, 0x04, 0x00, 0x42, 0x12, 0xab 2018-09-01 14:07:52.499 Detail, 2018-09-01 14:07:52.499 Info, contrlr, Sending (Command) message (Callback ID=0x12, Expected Reply=0x42) - Reset controller and erase all node information: 0x01, 0x04, 0x00, 0x42, 0x12, 0xab 2018-09-01 14:07:54.699 Detail, contrlr, Received: 0x01, 0x04, 0x00, 0x42, 0x12, 0xab 2018-09-01 14:07:54.699 Detail, 2018-09-01 14:07:54.699 Info, contrlr, Received reply to complete Controller Reset. 2018-09-01 14:07:54.699 Detail, Node171, Expected callbackId was received 2018-09-01 14:07:54.699 Detail, Node171, Expected reply was received 2018-09-01 14:07:54.699 Detail, Node171, Message transaction complete 2018-09-01 14:07:54.700 Detail, 2018-09-01 14:07:54.700 Detail, contrlr, Removing current message 2018-09-01 14:07:54.700 Info, mgr, Driver for controller /dev/ttyACM0 pending removal 2018-09-01 14:07:54.700 Detail, Notification: DriverRemoved 2018-09-01 14:07:54.700 Always, *************************************************************************** 2018-09-01 14:07:54.700 Always, ********************* Cumulative Network Statistics ********************* 2018-09-01 14:07:54.700 Always, *** General 2018-09-01 14:07:54.700 Always, Driver run time: . . . 0 days, 0 hours, 14 minutes 2018-09-01 14:07:54.700 Always, Frames processed: . . . . . . . . . . . . . . . . . . . . 15 2018-09-01 14:07:54.701 Always, Total messages successfully received: . . . . . . . . . . 15 2018-09-01 14:07:54.701 Always, Total Messages successfully sent: . . . . . . . . . . . . 14 2018-09-01 14:07:54.701 Always, ACKs received from controller: . . . . . . . . . . . . . 14 2018-09-01 14:07:54.701 Always, *** Errors 2018-09-01 14:07:54.701 Always, Unsolicited messages received while waiting for ACK: . . 0 2018-09-01 14:07:54.701 Always, Reads aborted due to timeouts: . . . . . . . . . . . . . 0 2018-09-01 14:07:54.701 Always, Bad checksum errors: . . . . . . . . . . . . . . . . . . 0 2018-09-01 14:07:54.701 Always, CANs received from controller: . . . . . . . . . . . . . 0 2018-09-01 14:07:54.701 Always, NAKs received from controller: . . . . . . . . . . . . . 0 2018-09-01 14:07:54.701 Always, Out of frame data flow errors: . . . . . . . . . . . . . 0 2018-09-01 14:07:54.701 Always, Messages retransmitted: . . . . . . . . . . . . . . . . . 0 2018-09-01 14:07:54.702 Always, Messages dropped and not delivered: . . . . . . . . . . . 0 2018-09-01 14:07:54.702 Always, *************************************************************************** 2018-09-01 14:07:54.704 Detail, WriteMsg Wait Timeout m_currentMsg=00000000 2018-09-01 14:07:56.721 Info, mgr, Driver for controller /dev/ttyACM0 removed 2018-09-01 14:07:56.721 Info, Setting Up Provided Network Key for Secure Communications 2018-09-01 14:07:56.721 Info, mgr, Added driver for controller /dev/ttyACM0 2018-09-01 14:07:56.721 Info, Opening controller /dev/ttyACM0 2018-09-01 14:07:56.722 Info, Trying to open serial port /dev/ttyACM0 (attempt 1) 2018-09-01 14:07:56.722 Info, Serial port /dev/ttyACM0 opened (attempt 1) 2018-09-01 14:07:56.723 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2018-09-01 14:07:56.723 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2018-09-01 14:07:56.723 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2018-09-01 14:07:56.723 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2018-09-01 14:07:56.723 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa 2018-09-01 14:07:56.723 Detail, 2018-09-01 14:07:56.723 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2018-09-01 14:07:56.725 Detail, contrlr, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97 2018-09-01 14:07:56.726 Detail, 2018-09-01 14:07:56.726 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION: 2018-09-01 14:07:56.726 Info, contrlr, Static Controller library, version Z-Wave 4.05 2018-09-01 14:07:56.726 Detail, Node045, Expected reply was received 2018-09-01 14:07:56.726 Detail, Node045, Message transaction complete 2018-09-01 14:07:56.726 Detail, 2018-09-01 14:07:56.726 Detail, contrlr, Removing current message 2018-09-01 14:07:56.726 Detail, 2018-09-01 14:07:56.726 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2018-09-01 14:07:56.728 Detail, contrlr, Received: 0x01, 0x08, 0x01, 0x20, 0xe7, 0xde, 0xc0, 0xef, 0x01, 0xc1 2018-09-01 14:07:56.728 Detail, 2018-09-01 14:07:56.728 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xe7dec0ef. Our node ID = 1 2018-09-01 14:07:56.728 Detail, Node222, Expected reply was received 2018-09-01 14:07:56.728 Detail, Node222, Message transaction complete 2018-09-01 14:07:56.729 Detail, 2018-09-01 14:07:56.729 Detail, contrlr, Removing current message 2018-09-01 14:07:56.729 Detail, 2018-09-01 14:07:56.729 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2018-09-01 14:07:56.730 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7 2018-09-01 14:07:56.730 Detail, 2018-09-01 14:07:56.730 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 2018-09-01 14:07:56.730 Info, contrlr, There is no SUC ID Server (SIS) in this network. 2018-09-01 14:07:56.730 Info, contrlr, The PC controller is a primary controller. 2018-09-01 14:07:56.730 Detail, Node215, Expected reply was received 2018-09-01 14:07:56.730 Detail, Node215, Message transaction complete 2018-09-01 14:07:56.730 Detail, 2018-09-01 14:07:56.730 Detail, contrlr, Removing current message 2018-09-01 14:07:56.730 Detail, 2018-09-01 14:07:56.730 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2018-09-01 14:07:56.734 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 2018-09-01 14:07:56.734 Detail, 2018-09-01 14:07:56.734 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES 2018-09-01 14:07:56.734 Info, contrlr, Serial API Version: 5.5 2018-09-01 14:07:56.734 Info, contrlr, Manufacturer ID: 0x0115 2018-09-01 14:07:56.735 Info, contrlr, Product Type: 0x0400 2018-09-01 14:07:56.735 Info, contrlr, Product ID: 0x0001 2018-09-01 14:07:56.735 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7 2018-09-01 14:07:56.735 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2018-09-01 14:07:56.735 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97 2018-09-01 14:07:56.735 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9 2018-09-01 14:07:56.735 Detail, Node005, Expected reply was received 2018-09-01 14:07:56.735 Detail, Node005, Message transaction complete 2018-09-01 14:07:56.735 Detail, 2018-09-01 14:07:56.735 Detail, contrlr, Removing current message 2018-09-01 14:07:56.735 Detail, 2018-09-01 14:07:56.735 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa 2018-09-01 14:07:56.737 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac 2018-09-01 14:07:56.737 Detail, 2018-09-01 14:07:56.737 Info, contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0 2018-09-01 14:07:56.737 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node 2018-09-01 14:07:56.737 Detail, Node172, Expected reply was received 2018-09-01 14:07:56.737 Detail, Node172, Message transaction complete 2018-09-01 14:07:56.737 Detail, 2018-09-01 14:07:56.737 Detail, contrlr, Removing current message 2018-09-01 14:07:56.737 Detail, 2018-09-01 14:07:56.737 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7 2018-09-01 14:07:56.793 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x19, 0x4d, 0x41, 0x47, 0x3e, 0x1b, 0x5d, 0xed, 0x47, 0x55, 0x6e, 0x58, 0x42, 0x33, 0x77, 0x47, 0xd5, 0x43, 0xf0, 0x4c, 0x77, 0xee, 0xfb, 0x90, 0xa0, 0xe6, 0x4d, 0xc5, 0xb2, 0xdd, 0xcd, 0xa6, 0x56 2018-09-01 14:07:56.793 Detail, 2018-09-01 14:07:56.793 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true 2018-09-01 14:07:56.793 Detail, Node032, Expected reply was received 2018-09-01 14:07:56.794 Detail, Node032, Message transaction complete 2018-09-01 14:07:56.794 Detail, 2018-09-01 14:07:56.794 Detail, contrlr, Removing current message 2018-09-01 14:07:56.794 Detail, 2018-09-01 14:07:56.794 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2018-09-01 14:07:56.892 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 2018-09-01 14:07:56.893 Detail, 2018-09-01 14:07:56.893 Info, mgr, Driver with Home ID of 0xe7dec0ef is now ready. 2018-09-01 14:07:56.893 Info, 2018-09-01 14:07:56.893 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA: 2018-09-01 14:07:56.893 Info, contrlr, Node 001 - New 2018-09-01 14:07:56.893 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1 2018-09-01 14:07:56.893 Detail, Node001, QueryStage_ProtocolInfo 2018-09-01 14:07:56.893 Detail, Node001, Queuing (Query) Get Node Protocol Info (Node=1): 0x01, 0x04, 0x00, 0x41, 0x01, 0xbb 2018-09-01 14:07:56.893 Detail, Node001, Queuing (Query) Query Stage Complete (ProtocolInfo) 2018-09-01 14:07:56.893 Info, Node001, Initializing Node. New Node: false (false) 2018-09-01 14:07:56.893 Detail, Expected reply was received 2018-09-01 14:07:56.894 Detail, Message transaction complete 2018-09-01 14:07:56.894 Detail, 2018-09-01 14:07:56.894 Detail, contrlr, Removing current message 2018-09-01 14:07:56.894 Detail, Node001, Notification: DriverReady 2018-09-01 14:07:56.894 Detail, Node001, Notification: NodeNew 2018-09-01 14:07:56.894 Detail, Node001, Notification: NodeAdded 2018-09-01 14:07:56.894 Detail, 2018-09-01 14:07:56.894 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-09-01 14:07:56.904 Detail, contrlr, Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96 2018-09-01 14:07:56.904 Detail, 2018-09-01 14:07:56.904 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS 2018-09-01 14:07:56.904 Detail, Node015, Expected reply was received 2018-09-01 14:07:56.904 Detail, Node015, Message transaction complete 2018-09-01 14:07:56.904 Detail, 2018-09-01 14:07:56.904 Detail, contrlr, Removing current message 2018-09-01 14:07:56.904 Detail, 2018-09-01 14:07:56.904 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-09-01 14:07:56.907 Detail, contrlr, Removing current message 2018-09-01 14:07:56.907 Detail, 2018-09-01 14:07:56.907 Info, Node001, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=1): 0x01, 0x04, 0x00, 0x41, 0x01, 0xbb 2018-09-01 14:07:56.909 Detail, Node001, Received: 0x01, 0x09, 0x01, 0x41, 0x93, 0x16, 0x01, 0x02, 0x02, 0x01, 0x33 2018-09-01 14:07:56.909 Detail, 2018-09-01 14:07:56.910 Info, Node001, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO 2018-09-01 14:07:56.910 Info, Node001, Protocol Info for Node 1: 2018-09-01 14:07:56.910 Info, Node001, Listening = true 2018-09-01 14:07:56.910 Info, Node001, Beaming = true 2018-09-01 14:07:56.910 Info, Node001, Routing = false 2018-09-01 14:07:56.910 Info, Node001, Max Baud Rate = 40000 2018-09-01 14:07:56.910 Info, Node001, Version = 4 2018-09-01 14:07:56.910 Info, Node001, Security = false 2018-09-01 14:07:56.910 Info, Node001, Basic device class (0x02) - Static Controller 2018-09-01 14:07:56.910 Info, Node001, Generic device Class (0x02) - Static Controller 2018-09-01 14:07:56.910 Info, Node001, Specific device class (0x01) - Static PC Controller 2018-09-01 14:07:56.910 Info, Node001, COMMAND_CLASS_BASIC is not mapped 2018-09-01 14:07:56.910 Info, Node001, Mandatory Command Classes for Node 1: 2018-09-01 14:07:56.910 Info, Node001, None 2018-09-01 14:07:56.910 Info, Node001, Mandatory Command Classes controlled by Node 1: 2018-09-01 14:07:56.910 Info, Node001, COMMAND_CLASS_BASIC 2018-09-01 14:07:56.910 Detail, Node022, Expected reply was received 2018-09-01 14:07:56.910 Detail, Node022, Message transaction complete 2018-09-01 14:07:56.911 Detail, 2018-09-01 14:07:56.911 Detail, Node001, Removing current message 2018-09-01 14:07:56.911 Detail, Node001, Notification: NodeProtocolInfo 2018-09-01 14:07:56.911 Detail, Node001, Notification: ValueAdded 2018-09-01 14:07:56.911 Detail, Node001, Query Stage Complete (ProtocolInfo) 2018-09-01 14:07:56.911 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1 2018-09-01 14:07:56.911 Detail, Node001, QueryStage_Probe 2018-09-01 14:07:56.911 Detail, Node001, QueryStage_WakeUp 2018-09-01 14:07:56.911 Detail, Node001, QueryStage_ManufacturerSpecific1 2018-09-01 14:07:56.911 Detail, Node001, Load Controller Manufacturer Specific Config 2018-09-01 14:07:56.961 Info, Product name collision: VRS15-1LZ Switch type 301 id 209 manufacturerid 1d, collides with VRS15-1LZ Binary Scene Switch, type 301 id 209 manufacturerid 1d 2018-09-01 14:07:56.961 Info, Product name collision: VRF01-1LZ Multilevel Scene Switch - 1.5A Fan type 1001 id 209 manufacturerid 1d, collides with VRF01-1LZ Quiet Fan Speed Control, type 1001 id 209 manufacturerid 1d 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_NodePlusInfo 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_SecurityReport 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_ManufacturerSpecific2 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_Versions 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_Instances 2018-09-01 14:07:56.966 Info, Node001, Essential node queries are complete 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_Static 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_Associations 2018-09-01 14:07:56.966 Detail, Node001, QueryStage_Neighbors 2018-09-01 14:07:56.966 Detail, Requesting routing info (neighbor list) for Node 1 2018-09-01 14:07:56.968 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a 2018-09-01 14:07:56.968 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors) 2018-09-01 14:07:56.968 Detail, Node001, Notification: EssentialNodeQueriesComplete 2018-09-01 14:07:56.968 Detail, 2018-09-01 14:07:56.968 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-09-01 14:07:56.973 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-09-01 14:07:56.973 Detail, 2018-09-01 14:07:56.973 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2018-09-01 14:07:56.973 Info, Node001, Neighbors of this node are: 2018-09-01 14:07:56.973 Info, Node001, (none reported) 2018-09-01 14:07:56.973 Detail, Expected reply was received 2018-09-01 14:07:56.973 Detail, Message transaction complete 2018-09-01 14:07:56.973 Detail, 2018-09-01 14:07:56.973 Detail, Node001, Removing current message 2018-09-01 14:07:56.973 Detail, Node001, Query Stage Complete (Neighbors) 2018-09-01 14:07:56.973 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1 2018-09-01 14:07:56.973 Detail, Node001, QueryStage_Session 2018-09-01 14:07:56.973 Detail, Node001, QueryStage_Dynamic 2018-09-01 14:07:56.973 Detail, Node001, QueryStage_Configuration 2018-09-01 14:07:56.973 Detail, Node001, QueryStage_Complete 2018-09-01 14:07:56.973 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0 2018-09-01 14:07:56.973 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1 2018-09-01 14:07:56.974 Info, Node query processing complete. 2018-09-01 14:07:56.974 Detail, Node001, Notification: NodeQueriesComplete 2018-09-01 14:07:56.974 Detail, contrlr, Notification: AllNodesQueried 2018-09-01 14:10:02.972 Detail, Queuing (Controller) Add Device 2018-09-01 14:10:02.973 Info, Add Device 2018-09-01 14:10:02.974 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x13, 0x62 2018-09-01 14:10:02.974 Detail, Notification: ControllerCommand - Starting 2018-09-01 14:10:02.974 Detail, 2018-09-01 14:10:02.974 Info, contrlr, Sending (Command) message (Callback ID=0x13, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x13, 0x62 2018-09-01 14:10:02.985 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x13, 0x01, 0x00, 0x00, 0xa0 2018-09-01 14:10:02.985 Detail, 2018-09-01 14:10:02.985 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:10:02.985 Info, contrlr, ADD_NODE_STATUS_LEARN_READY 2018-09-01 14:10:02.985 Detail, Node001, Expected callbackId was received 2018-09-01 14:10:02.986 Detail, Node001, Expected reply was received 2018-09-01 14:10:02.986 Detail, Node001, Message transaction complete 2018-09-01 14:10:02.986 Detail, 2018-09-01 14:10:02.986 Detail, contrlr, Removing current message 2018-09-01 14:10:02.986 Detail, Notification: ControllerCommand - Waiting 2018-09-01 14:10:02.986 Info, WriteNextMsg Controller nothing to do 2018-09-01 14:10:33.002 Info, Cancel Add Node 2018-09-01 14:10:33.002 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:10:33.003 Detail, Notification: ControllerCommand - Canceled 2018-09-01 14:10:33.003 Detail, 2018-09-01 14:10:33.003 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:10:33.154 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x13, 0x06, 0x00, 0x00, 0xa7 2018-09-01 14:10:33.155 Detail, 2018-09-01 14:10:33.155 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:10:33.155 Info, contrlr, ADD_NODE_STATUS_DONE 2018-09-01 14:10:33.155 Detail, Node006, Expected reply was received 2018-09-01 14:10:33.155 Detail, Node006, Message transaction complete 2018-09-01 14:10:33.155 Detail, 2018-09-01 14:10:33.155 Detail, contrlr, Removing current message 2018-09-01 14:10:33.155 Detail, Notification: ControllerCommand - Completed 2018-09-01 14:10:46.732 Detail, Queuing (Controller) Add Device 2018-09-01 14:10:46.733 Info, Add Device 2018-09-01 14:10:46.733 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65 2018-09-01 14:10:46.733 Detail, Notification: ControllerCommand - Starting 2018-09-01 14:10:46.733 Detail, 2018-09-01 14:10:46.734 Info, contrlr, Sending (Command) message (Callback ID=0x14, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x14, 0x65 2018-09-01 14:10:46.737 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x01, 0x00, 0x00, 0xa7 2018-09-01 14:10:46.737 Detail, 2018-09-01 14:10:46.738 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:10:46.738 Info, contrlr, ADD_NODE_STATUS_LEARN_READY 2018-09-01 14:10:46.738 Detail, Node001, Expected callbackId was received 2018-09-01 14:10:46.738 Detail, Node001, Expected reply was received 2018-09-01 14:10:46.738 Detail, Node001, Message transaction complete 2018-09-01 14:10:46.738 Detail, 2018-09-01 14:10:46.738 Detail, contrlr, Removing current message 2018-09-01 14:10:46.738 Detail, Notification: ControllerCommand - Waiting 2018-09-01 14:10:46.739 Info, WriteNextMsg Controller nothing to do 2018-09-01 14:10:59.490 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x02, 0x00, 0x00, 0xa4 2018-09-01 14:10:59.490 Detail, 2018-09-01 14:10:59.490 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:10:59.490 Info, ADD_NODE_STATUS_NODE_FOUND 2018-09-01 14:10:59.490 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:10:59.670 Detail, Received: 0x01, 0x16, 0x00, 0x4a, 0x14, 0x03, 0x02, 0x0f, 0x04, 0x07, 0x01, 0x5e, 0x86, 0x72, 0x5a, 0x73, 0x80, 0x71, 0x30, 0x85, 0x59, 0x84, 0x70, 0xd1 2018-09-01 14:10:59.670 Detail, 2018-09-01 14:10:59.670 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:10:59.670 Info, ADD_NODE_STATUS_ADDING_SLAVE 2018-09-01 14:10:59.670 Info, Adding node ID 2 - Non-Secure 2018-09-01 14:10:59.670 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:11:00.174 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x05, 0x02, 0x00, 0xa1 2018-09-01 14:11:00.174 Detail, 2018-09-01 14:11:00.179 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:11:00.179 Info, ADD_NODE_STATUS_PROTOCOL_DONE 2018-09-01 14:11:00.179 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:11:00.179 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:11:00.179 Detail, 2018-09-01 14:11:00.179 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:11:00.330 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x14, 0x06, 0x02, 0x00, 0xa2 2018-09-01 14:11:00.331 Detail, 2018-09-01 14:11:00.331 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:11:00.331 Info, contrlr, ADD_NODE_STATUS_DONE 2018-09-01 14:11:00.331 Info, Node002, Basic device class (0x04) - Routing Slave 2018-09-01 14:11:00.331 Info, Node002, Generic device Class (0x07) - Notification Sensor 2018-09-01 14:11:00.331 Info, Node002, Specific device class (0x01) - Notification Sensor 2018-09-01 14:11:00.331 Info, Node002, COMMAND_CLASS_BASIC is not mapped 2018-09-01 14:11:00.331 Info, Node002, Mandatory Command Classes for Node 2: 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_BASIC 2018-09-01 14:11:00.332 Info, Node002, Mandatory Command Classes controlled by Node 2: 2018-09-01 14:11:00.332 Info, Node002, None 2018-09-01 14:11:00.332 Info, Node002, Optional command classes for node 2: 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_ZWAVE_PLUS_INFO 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_VERSION 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_MANUFACTURER_SPECIFIC 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_DEVICE_RESET_LOCALLY 2018-09-01 14:11:00.332 Info, Node002, COMMAND_CLASS_POWERLEVEL 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_BATTERY 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_ALARM 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_SENSOR_BINARY 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_ASSOCIATION 2018-09-01 14:11:00.333 Info, Node002, CommandClass 0x59 - NOT REQUIRED 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_WAKE_UP 2018-09-01 14:11:00.333 Info, Node002, COMMAND_CLASS_CONFIGURATION 2018-09-01 14:11:00.333 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1 2018-09-01 14:11:00.333 Detail, Node002, QueryStage_ProtocolInfo 2018-09-01 14:11:00.334 Detail, Node002, Queuing (Query) Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8 2018-09-01 14:11:00.334 Detail, Node002, Queuing (Query) Query Stage Complete (ProtocolInfo) 2018-09-01 14:11:00.334 Info, Node002, Initializing Node. New Node: true (true) 2018-09-01 14:11:00.334 Detail, Node006, Expected reply was received 2018-09-01 14:11:00.334 Detail, Node006, Message transaction complete 2018-09-01 14:11:00.334 Detail, 2018-09-01 14:11:00.334 Detail, contrlr, Removing current message 2018-09-01 14:11:00.334 Detail, Node002, Notification: NodeAdded 2018-09-01 14:11:00.334 Detail, Node002, Notification: NodeProtocolInfo 2018-09-01 14:11:00.344 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.345 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.346 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.347 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.347 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.347 Detail, Node002, Notification: ValueAdded 2018-09-01 14:11:00.347 Detail, Notification: ControllerCommand - Completed 2018-09-01 14:11:00.438 Detail, 2018-09-01 14:11:00.438 Info, Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8 2018-09-01 14:11:00.441 Detail, Node002, Received: 0x01, 0x09, 0x01, 0x41, 0x53, 0x9c, 0x01, 0x04, 0x07, 0x01, 0x7a 2018-09-01 14:11:00.441 Detail, 2018-09-01 14:11:00.441 Info, Node002, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO 2018-09-01 14:11:00.441 Info, Node002, Protocol Info for Node 2: 2018-09-01 14:11:00.441 Info, Node002, Listening = false 2018-09-01 14:11:00.441 Info, Node002, Frequent = false 2018-09-01 14:11:00.441 Info, Node002, Beaming = true 2018-09-01 14:11:00.441 Info, Node002, Routing = true 2018-09-01 14:11:00.441 Info, Node002, Max Baud Rate = 40000 2018-09-01 14:11:00.441 Info, Node002, Version = 4 2018-09-01 14:11:00.441 Info, Node002, Security = false 2018-09-01 14:11:00.441 Detail, Node156, Expected reply was received 2018-09-01 14:11:00.441 Detail, Node156, Message transaction complete 2018-09-01 14:11:00.441 Detail, 2018-09-01 14:11:00.441 Detail, Node002, Removing current message 2018-09-01 14:11:00.442 Detail, Node002, Query Stage Complete (ProtocolInfo) 2018-09-01 14:11:00.442 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1 2018-09-01 14:11:00.442 Detail, Node002, QueryStage_Probe 2018-09-01 14:11:00.442 Info, Node002, NoOperation::Set - Routing=true 2018-09-01 14:11:00.442 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x15, 0xd5 2018-09-01 14:11:00.442 Detail, Node002, Queuing (Query) Query Stage Complete (Probe) 2018-09-01 14:11:00.442 Detail, 2018-09-01 14:11:00.442 Info, Node002, Sending (NoOp) message (Callback ID=0x15, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x15, 0xd5 2018-09-01 14:11:00.454 Detail, Node002, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2018-09-01 14:11:00.454 Detail, Node002, ZW_SEND_DATA delivered to Z-Wave stack 2018-09-01 14:11:04.568 Detail, Node002, Received: 0x01, 0x07, 0x00, 0x13, 0x15, 0x01, 0x01, 0x9d, 0x63 2018-09-01 14:11:04.568 Detail, Node002, ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15) 2018-09-01 14:11:04.568 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep. 2018-09-01 14:11:04.568 Info, Node002, Node 2 has been marked as asleep 2018-09-01 14:11:04.568 Info, Node002, Node not responding - moving QueryStageComplete command to Wake-Up queue 2018-09-01 14:11:04.568 Detail, Node002, Notification: Notification - NoOperation 2018-09-01 14:11:04.569 Detail, Node002, Notification: Notification - Node Asleep 2018-09-01 14:11:40.253 Detail, Queuing (Controller) Add Device 2018-09-01 14:11:40.254 Info, Add Device 2018-09-01 14:11:40.254 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67 2018-09-01 14:11:40.254 Detail, Notification: ControllerCommand - Starting 2018-09-01 14:11:40.255 Detail, 2018-09-01 14:11:40.255 Info, contrlr, Sending (Command) message (Callback ID=0x16, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x16, 0x67 2018-09-01 14:11:40.258 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x16, 0x01, 0x00, 0x00, 0xa5 2018-09-01 14:11:40.258 Detail, 2018-09-01 14:11:40.258 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:11:40.258 Info, contrlr, ADD_NODE_STATUS_LEARN_READY 2018-09-01 14:11:40.258 Detail, Node001, Expected callbackId was received 2018-09-01 14:11:40.258 Detail, Node001, Expected reply was received 2018-09-01 14:11:40.259 Detail, Node001, Message transaction complete 2018-09-01 14:11:40.259 Detail, 2018-09-01 14:11:40.259 Detail, contrlr, Removing current message 2018-09-01 14:11:40.259 Detail, Notification: ControllerCommand - Waiting 2018-09-01 14:11:40.259 Info, WriteNextMsg Controller nothing to do 2018-09-01 14:12:10.288 Info, Cancel Add Node 2018-09-01 14:12:10.288 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:12:10.288 Detail, Notification: ControllerCommand - Canceled 2018-09-01 14:12:10.288 Detail, 2018-09-01 14:12:10.289 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:12:10.439 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x16, 0x06, 0x02, 0x00, 0xa0 2018-09-01 14:12:10.440 Detail, 2018-09-01 14:12:10.440 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:10.440 Info, contrlr, ADD_NODE_STATUS_DONE 2018-09-01 14:12:10.440 Detail, Node006, Expected reply was received 2018-09-01 14:12:10.440 Detail, Node006, Message transaction complete 2018-09-01 14:12:10.440 Detail, 2018-09-01 14:12:10.440 Detail, contrlr, Removing current message 2018-09-01 14:12:10.440 Detail, Notification: ControllerCommand - Completed 2018-09-01 14:12:30.251 Detail, Queuing (Controller) Add Device 2018-09-01 14:12:30.252 Info, Add Device 2018-09-01 14:12:30.252 Detail, contrlr, Queuing (Command) ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x17, 0x66 2018-09-01 14:12:30.252 Detail, Notification: ControllerCommand - Starting 2018-09-01 14:12:30.252 Detail, 2018-09-01 14:12:30.252 Info, contrlr, Sending (Command) message (Callback ID=0x17, Expected Reply=0x4a) - ControllerCommand_AddDevice: 0x01, 0x05, 0x00, 0x4a, 0xc1, 0x17, 0x66 2018-09-01 14:12:30.256 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x17, 0x01, 0x00, 0x00, 0xa4 2018-09-01 14:12:30.257 Detail, 2018-09-01 14:12:30.257 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:30.257 Info, contrlr, ADD_NODE_STATUS_LEARN_READY 2018-09-01 14:12:30.257 Detail, Node001, Expected callbackId was received 2018-09-01 14:12:30.257 Detail, Node001, Expected reply was received 2018-09-01 14:12:30.257 Detail, Node001, Message transaction complete 2018-09-01 14:12:30.257 Detail, 2018-09-01 14:12:30.257 Detail, contrlr, Removing current message 2018-09-01 14:12:30.258 Detail, Notification: ControllerCommand - Waiting 2018-09-01 14:12:30.258 Info, WriteNextMsg Controller nothing to do 2018-09-01 14:12:36.321 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x17, 0x02, 0x00, 0x00, 0xa7 2018-09-01 14:12:36.321 Detail, 2018-09-01 14:12:36.321 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:36.321 Info, ADD_NODE_STATUS_NODE_FOUND 2018-09-01 14:12:36.321 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:12:36.494 Detail, Received: 0x01, 0x16, 0x00, 0x4a, 0x17, 0x03, 0x03, 0x0f, 0x04, 0x07, 0x01, 0x5e, 0x86, 0x72, 0x5a, 0x73, 0x80, 0x71, 0x30, 0x85, 0x59, 0x84, 0x70, 0xd3 2018-09-01 14:12:36.494 Detail, 2018-09-01 14:12:36.494 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:36.494 Info, ADD_NODE_STATUS_ADDING_SLAVE 2018-09-01 14:12:36.494 Info, Adding node ID 3 - Non-Secure 2018-09-01 14:12:36.494 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:12:40.451 Detail, Received: 0x01, 0x07, 0x00, 0x4a, 0x17, 0x05, 0x03, 0x00, 0xa3 2018-09-01 14:12:40.451 Detail, 2018-09-01 14:12:40.451 Info, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:40.451 Info, ADD_NODE_STATUS_PROTOCOL_DONE 2018-09-01 14:12:40.451 Detail, contrlr, Queuing (Command) Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:12:40.452 Detail, Notification: ControllerCommand - InProgress 2018-09-01 14:12:40.452 Detail, 2018-09-01 14:12:40.452 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4 2018-09-01 14:12:40.603 Detail, contrlr, Received: 0x01, 0x07, 0x00, 0x4a, 0x17, 0x06, 0x03, 0x00, 0xa0 2018-09-01 14:12:40.604 Detail, 2018-09-01 14:12:40.604 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK: 2018-09-01 14:12:40.604 Info, contrlr, ADD_NODE_STATUS_DONE 2018-09-01 14:12:40.604 Info, Node003, Basic device class (0x04) - Routing Slave 2018-09-01 14:12:40.604 Info, Node003, Generic device Class (0x07) - Notification Sensor 2018-09-01 14:12:40.604 Info, Node003, Specific device class (0x01) - Notification Sensor 2018-09-01 14:12:40.604 Info, Node003, COMMAND_CLASS_BASIC is not mapped 2018-09-01 14:12:40.604 Info, Node003, Mandatory Command Classes for Node 3: 2018-09-01 14:12:40.604 Info, Node003, COMMAND_CLASS_BASIC 2018-09-01 14:12:40.605 Info, Node003, Mandatory Command Classes controlled by Node 3: 2018-09-01 14:12:40.605 Info, Node003, None 2018-09-01 14:12:40.605 Info, Node003, Optional command classes for node 3: 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_ZWAVE_PLUS_INFO 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_VERSION 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_MANUFACTURER_SPECIFIC 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_DEVICE_RESET_LOCALLY 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_POWERLEVEL 2018-09-01 14:12:40.605 Info, Node003, COMMAND_CLASS_BATTERY 2018-09-01 14:12:40.606 Info, Node003, COMMAND_CLASS_ALARM 2018-09-01 14:12:40.606 Info, Node003, COMMAND_CLASS_SENSOR_BINARY 2018-09-01 14:12:40.606 Info, Node003, COMMAND_CLASS_ASSOCIATION 2018-09-01 14:12:40.606 Info, Node003, CommandClass 0x59 - NOT REQUIRED 2018-09-01 14:12:40.606 Info, Node003, COMMAND_CLASS_WAKE_UP 2018-09-01 14:12:40.606 Info, Node003, COMMAND_CLASS_CONFIGURATION 2018-09-01 14:12:40.606 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1 2018-09-01 14:12:40.606 Detail, Node003, QueryStage_ProtocolInfo 2018-09-01 14:12:40.606 Detail, Node003, Queuing (Query) Get Node Protocol Info (Node=3): 0x01, 0x04, 0x00, 0x41, 0x03, 0xb9 2018-09-01 14:12:40.606 Detail, Node003, Queuing (Query) Query Stage Complete (ProtocolInfo) 2018-09-01 14:12:40.607 Info, Node003, Initializing Node. New Node: true (true) 2018-09-01 14:12:40.607 Detail, Node006, Expected reply was received 2018-09-01 14:12:40.607 Detail, Node006, Message transaction complete 2018-09-01 14:12:40.607 Detail, 2018-09-01 14:12:40.607 Detail, contrlr, Removing current message 2018-09-01 14:12:40.607 Detail, Node003, Notification: NodeAdded 2018-09-01 14:12:40.607 Detail, Node003, Notification: NodeProtocolInfo 2018-09-01 14:12:40.625 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.625 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.625 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.625 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.625 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.626 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Node003, Notification: ValueAdded 2018-09-01 14:12:40.627 Detail, Notification: ControllerCommand - Completed 2018-09-01 14:12:40.628 Detail, 2018-09-01 14:12:40.628 Info, Node003, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=3): 0x01, 0x04, 0x00, 0x41, 0x03, 0xb9 2018-09-01 14:12:40.631 Detail, Node003, Received: 0x01, 0x09, 0x01, 0x41, 0x53, 0x9c, 0x01, 0x04, 0x07, 0x01, 0x7a 2018-09-01 14:12:40.632 Detail, 2018-09-01 14:12:40.632 Info, Node003, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO 2018-09-01 14:12:40.632 Info, Node003, Protocol Info for Node 3: 2018-09-01 14:12:40.632 Info, Node003, Listening = false 2018-09-01 14:12:40.632 Info, Node003, Frequent = false 2018-09-01 14:12:40.632 Info, Node003, Beaming = true 2018-09-01 14:12:40.632 Info, Node003, Routing = true 2018-09-01 14:12:40.632 Info, Node003, Max Baud Rate = 40000 2018-09-01 14:12:40.632 Info, Node003, Version = 4 2018-09-01 14:12:40.632 Info, Node003, Security = false 2018-09-01 14:12:40.633 Detail, Node156, Expected reply was received 2018-09-01 14:12:40.633 Detail, Node156, Message transaction complete 2018-09-01 14:12:40.633 Detail, 2018-09-01 14:12:40.633 Detail, Node003, Removing current message 2018-09-01 14:12:40.633 Detail, Node003, Query Stage Complete (ProtocolInfo) 2018-09-01 14:12:40.633 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1 2018-09-01 14:12:40.633 Detail, Node003, QueryStage_Probe 2018-09-01 14:12:40.633 Info, Node003, NoOperation::Set - Routing=true 2018-09-01 14:12:40.633 Detail, Node003, Queuing (NoOp) NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x18, 0xd9 2018-09-01 14:12:40.634 Detail, Node003, Queuing (Query) Query Stage Complete (Probe) 2018-09-01 14:12:40.634 Detail, 2018-09-01 14:12:40.634 Info, Node003, Sending (NoOp) message (Callback ID=0x18, Expected Reply=0x13) - NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x18, 0xd9 2018-09-01 14:12:40.646 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2018-09-01 14:12:40.647 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack 2018-09-01 14:12:40.770 Detail, Node003, Received: 0x01, 0x07, 0x00, 0x13, 0x18, 0x00, 0x00, 0x0d, 0xfe 2018-09-01 14:12:40.770 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x18 received (expected 0x18) 2018-09-01 14:12:40.770 Info, Node003, Request RTT 136 Average Request RTT 136 2018-09-01 14:12:40.770 Detail, Expected callbackId was received 2018-09-01 14:12:40.770 Detail, Expected reply was received 2018-09-01 14:12:40.770 Detail, Message transaction complete 2018-09-01 14:12:40.770 Detail, 2018-09-01 14:12:40.771 Detail, Node003, Removing current message 2018-09-01 14:12:40.771 Detail, Node003, Notification: Notification - NoOperation 2018-09-01 14:12:40.771 Detail, Node003, Query Stage Complete (Probe) 2018-09-01 14:12:40.771 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1 2018-09-01 14:12:40.771 Detail, Node003, QueryStage_WakeUp 2018-09-01 14:12:40.771 Detail, Node003, Queuing (WakeUp) WakeUpCmd_IntervalGet (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x84, 0x05, 0x25, 0x19, 0x59 2018-09-01 14:12:40.771 Detail, Node003, Queuing (Query) Query Stage Complete (WakeUp) 2018-09-01 14:12:40.771 Detail, 2018-09-01 14:12:40.771 Info, Node003, Sending (WakeUp) message (Callback ID=0x19, Expected Reply=0x04) - WakeUpCmd_IntervalGet (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x84, 0x05, 0x25, 0x19, 0x59 2018-09-01 14:12:40.783 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2018-09-01 14:12:40.783 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack 2018-09-01 14:12:40.887 Detail, Node003, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x03, 0x06, 0x84, 0x06, 0x00, 0xa8, 0xc0, 0x00, 0x18 2018-09-01 14:12:40.887 Detail, 2018-09-01 14:12:40.887 Info, Node003, Response RTT 115 Average Response RTT 115 2018-09-01 14:12:40.887 Info, Node003, Received Wakeup Interval report from node 3: Interval=43200, Target Node=0 2018-09-01 14:12:40.887 Detail, Node003, Initial read of value 2018-09-01 14:12:40.887 Detail, Node003, Queuing (WakeUp) WakeUpCmd_IntervalSet (Node=3): 0x01, 0x0d, 0x00, 0x13, 0x03, 0x06, 0x84, 0x04, 0x00, 0xa8, 0xc0, 0x01, 0x25, 0x1a, 0x32 2018-09-01 14:12:40.887 Detail, Node003, Notification: ValueChanged 2018-09-01 14:12:40.937 Detail, Node003, Received: 0x01, 0x07, 0x00, 0x13, 0x19, 0x00, 0x00, 0x11, 0xe3 2018-09-01 14:12:40.937 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19) 2018-09-01 14:12:40.937 Info, Node003, Request RTT 165 Average Request RTT 150 2018-09-01 14:12:40.937 Detail, Expected callbackId was received 2018-09-01 14:12:40.951 Detail, Node003, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x03, 0x06, 0x84, 0x06, 0x00, 0xa8, 0xc0, 0x00, 0x18 2018-09-01 14:12:40.951 Detail, 2018-09-01 14:12:40.951 Info, Node003, Response RTT 179 Average Response RTT 147 2018-09-01 14:12:40.951 Info, Node003, Received Wakeup Interval report from node 3: Interval=43200, Target Node=0 2018-09-01 14:12:40.951 Detail, Node003, Refreshed Value: old value=43200, new value=43200, type=int 2018-09-01 14:12:40.951 Detail, Node003, Changes to this value are not verified 2018-09-01 14:12:40.951 Detail, Node003, Queuing (WakeUp) WakeUpCmd_IntervalSet (Node=3): 0x01, 0x0d, 0x00, 0x13, 0x03, 0x06, 0x84, 0x04, 0x00, 0xa8, 0xc0, 0x01, 0x25, 0x1b, 0x33 2018-09-01 14:12:40.951 Detail, Node003, Expected reply and command class was received 2018-09-01 14:12:40.951 Detail, Node003, Message transaction complete 2018-09-01 14:12:40.952 Detail, 2018-09-01 14:12:40.952 Detail, Node003, Removing current message 2018-09-01 14:12:40.952 Detail, Node003, Notification: ValueChanged 2018-09-01 14:12:40.952 Detail, 2018-09-01 14:12:40.952 Info, Node003, Sending (WakeUp) message (Callback ID=0x1a, Expected Reply=0x13) - WakeUpCmd_IntervalSet (Node=3): 0x01, 0x0d, 0x00, 0x13, 0x03, 0x06, 0x84, 0x04, 0x00, 0xa8, 0xc0, 0x01, 0x25, 0x1a, 0x32 2018-09-01 14:12:40.965 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2018-09-01 14:12:40.965 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack 2018-09-01 14:12:45.066 Detail, Node003, Received: 0x01, 0x07, 0x00, 0x13, 0x1a, 0x01, 0x01, 0x9b, 0x6a 2018-09-01 14:12:45.067 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a) 2018-09-01 14:12:45.067 Info, Node003, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep. 2018-09-01 14:12:45.067 Info, Node003, Node 3 has been marked as asleep 2018-09-01 14:12:45.067 Info, Node003, Node not responding - moving message to Wake-Up queue: WakeUpCmd_IntervalSet (Node=3): 0x01, 0x0d, 0x00, 0x13, 0x03, 0x06, 0x84, 0x04, 0x00, 0xa8, 0xc0, 0x01, 0x25, 0x1a, 0x32 2018-09-01 14:12:45.067 Info, Node003, Node not responding - moving message to Wake-Up queue: WakeUpCmd_IntervalSet (Node=3): 0x01, 0x0d, 0x00, 0x13, 0x03, 0x06, 0x84, 0x04, 0x00, 0xa8, 0xc0, 0x01, 0x25, 0x1b, 0x33 2018-09-01 14:12:45.067 Info, Node003, Node not responding - moving QueryStageComplete command to Wake-Up queue 2018-09-01 14:12:45.067 Detail, Node003, Notification: Notification - Node Asleep