Mwz3v5VM

12019-12-05 18:27:26.409 Always, OpenZwave Version 1.4.3440 Starting Up
22019-12-05 18:27:38.422 Info, Setting Up Provided Network Key for Secure Communications
32019-12-05 18:27:38.422 Warning, Failed - Network Key Not Set
42019-12-05 18:27:38.422 Info, mgr,     Added driver for controller /dev/ttyUSB0
52019-12-05 18:27:38.422 Info,   Opening controller /dev/ttyUSB0
62019-12-05 18:27:38.422 Info, Trying to open serial port /dev/ttyUSB0 (attempt 1)
72019-12-05 18:27:38.423 Info, Serial port /dev/ttyUSB0 opened (attempt 1)
82019-12-05 18:27:38.423 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
92019-12-05 18:27:38.423 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
102019-12-05 18:27:38.423 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
112019-12-05 18:27:38.423 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
122019-12-05 18:27:38.423 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
132019-12-05 18:27:38.423 Detail,
142019-12-05 18:27:38.423 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
152019-12-05 18:27:38.424 Detail, Unsolicited message received while waiting for ACK.
162019-12-05 18:27:38.424 Detail, contrlr,   Received: 0x01, 0x05, 0x00, 0x48, 0x12, 0x23, 0x83
172019-12-05 18:27:38.424 Detail,
182019-12-05 18:27:38.424 Warning, contrlr, WARNING: REQUEST_NEIGHBOR_UPDATE_FAILED
192019-12-05 18:27:38.424 Detail, contrlr, CAN received...triggering resend
202019-12-05 18:27:38.424 Detail,
212019-12-05 18:27:38.424 Info, contrlr, Sending (Command) message (Attempt 2, Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
222019-12-05 18:27:38.427 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97
232019-12-05 18:27:38.427 Detail,
242019-12-05 18:27:38.427 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
252019-12-05 18:27:38.427 Info, contrlr,     Static Controller library, version Z-Wave 4.05
262019-12-05 18:27:38.427 Detail, Node045,   Expected reply was received
272019-12-05 18:27:38.427 Detail, Node045,   Message transaction complete
282019-12-05 18:27:38.427 Detail,
292019-12-05 18:27:38.427 Detail, contrlr, Removing current message
302019-12-05 18:27:38.427 Detail,
312019-12-05 18:27:38.427 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
322019-12-05 18:27:38.429 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xe4, 0x56, 0x4e, 0x50, 0x01, 0x7b
332019-12-05 18:27:38.429 Detail,
342019-12-05 18:27:38.429 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xe4564e50.  Our node ID = 1
352019-12-05 18:27:38.429 Detail, Node086,   Expected reply was received
362019-12-05 18:27:38.429 Detail, Node086,   Message transaction complete
372019-12-05 18:27:38.429 Detail,
382019-12-05 18:27:38.429 Detail, contrlr, Removing current message
392019-12-05 18:27:38.429 Detail,
402019-12-05 18:27:38.429 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
412019-12-05 18:27:38.431 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
422019-12-05 18:27:38.431 Detail,
432019-12-05 18:27:38.431 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
442019-12-05 18:27:38.431 Info, contrlr,     There is no SUC ID Server (SIS) in this network.
452019-12-05 18:27:38.431 Info, contrlr,     The PC controller is a primary controller.
462019-12-05 18:27:38.431 Detail, Node247,   Expected reply was received
472019-12-05 18:27:38.431 Detail, Node247,   Message transaction complete
482019-12-05 18:27:38.431 Detail,
492019-12-05 18:27:38.431 Detail, contrlr, Removing current message
502019-12-05 18:27:38.431 Detail,
512019-12-05 18:27:38.431 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
522019-12-05 18:27:38.436 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x04, 0x20, 0x00, 0x00, 0x00, 0x01, 0x00, 0x01, 0xfe, 0x81, 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, 0x60, 0x00, 0x00, 0x03
532019-12-05 18:27:38.436 Detail,
542019-12-05 18:27:38.436 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
552019-12-05 18:27:38.436 Info, contrlr,     Serial API Version:   4.32
562019-12-05 18:27:38.436 Info, contrlr,     Manufacturer ID:      0x0000
572019-12-05 18:27:38.436 Info, contrlr,     Product Type:         0x0001
582019-12-05 18:27:38.436 Info, contrlr,     Product ID:           0x0001
592019-12-05 18:27:38.436 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
602019-12-05 18:27:38.436 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
612019-12-05 18:27:38.436 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
622019-12-05 18:27:38.436 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
632019-12-05 18:27:38.436 Detail, Node032,   Expected reply was received
642019-12-05 18:27:38.436 Detail, Node032,   Message transaction complete
652019-12-05 18:27:38.436 Detail,
662019-12-05 18:27:38.436 Detail, contrlr, Removing current message
672019-12-05 18:27:38.436 Detail,
682019-12-05 18:27:38.436 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
692019-12-05 18:27:38.437 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
702019-12-05 18:27:38.437 Detail,
712019-12-05 18:27:38.437 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 0
722019-12-05 18:27:38.437 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node
732019-12-05 18:27:38.438 Detail, Node172,   Expected reply was received
742019-12-05 18:27:38.438 Detail, Node172,   Message transaction complete
752019-12-05 18:27:38.438 Detail,
762019-12-05 18:27:38.438 Detail, contrlr, Removing current message
772019-12-05 18:27:38.438 Detail,
782019-12-05 18:27:38.438 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
792019-12-05 18:27:38.491 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xb6, 0x9c, 0x02, 0x13, 0xcb, 0xc9, 0xb8, 0xad, 0x74, 0x78, 0x29, 0x7b, 0x0e, 0xba, 0xcc, 0x26, 0x31, 0x1d, 0x4a, 0xec, 0xca, 0x55, 0x62, 0x86, 0x77, 0xed, 0x5c, 0x5f, 0x89, 0xc5, 0xd1, 0x6b, 0x54
802019-12-05 18:27:38.491 Detail,
812019-12-05 18:27:38.491 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
822019-12-05 18:27:38.491 Detail, Node032,   Expected reply was received
832019-12-05 18:27:38.491 Detail, Node032,   Message transaction complete
842019-12-05 18:27:38.491 Detail,
852019-12-05 18:27:38.491 Detail, contrlr, Removing current message
862019-12-05 18:27:38.491 Detail,
872019-12-05 18:27:38.491 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
882019-12-05 18:27:38.547 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 0x05, 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, 0xc1
892019-12-05 18:27:38.547 Detail,
902019-12-05 18:27:38.547 Info, mgr,     Driver with Home ID of 0xe4564e50 is now ready.
912019-12-05 18:27:38.547 Info,
922019-12-05 18:27:38.547 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
932019-12-05 18:27:38.547 Info, contrlr,     Node 001 - Known
942019-12-05 18:27:38.547 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
952019-12-05 18:27:38.547 Detail, Node001, QueryStage_CacheLoad
962019-12-05 18:27:38.547 Info, Node001, Node Identity Codes: 0000:0001:0001
972019-12-05 18:27:38.547 Detail, Node001, QueryStage_Associations
982019-12-05 18:27:38.547 Detail, Node001, QueryStage_Neighbors
992019-12-05 18:27:38.547 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
1002019-12-05 18:27:38.547 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
1012019-12-05 18:27:38.547 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
1022019-12-05 18:27:38.547 Info, contrlr,     Node 003 - New
1032019-12-05 18:27:38.547 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
1042019-12-05 18:27:38.547 Detail, Node003, QueryStage_ProtocolInfo
1052019-12-05 18:27:38.547 Detail, Node003, Queuing (Query) Get Node Protocol Info (Node=3): 0x01, 0x04, 0x00, 0x41, 0x03, 0xb9
1062019-12-05 18:27:38.547 Detail, Node003, Queuing (Query) Query Stage Complete (ProtocolInfo)
1072019-12-05 18:27:38.547 Info, Node003, Initializing Node. New Node: false (false)
1082019-12-05 18:27:38.547 Detail,   Expected reply was received
1092019-12-05 18:27:38.547 Detail,   Message transaction complete
1102019-12-05 18:27:38.547 Detail,
1112019-12-05 18:27:38.547 Detail, contrlr, Removing current message
1122019-12-05 18:27:38.547 Detail, Node001, Notification: DriverReady
1132019-12-05 18:27:38.548 Detail, Node001, Notification: NodeAdded
1142019-12-05 18:27:38.549 Detail, Node001, Notification: NodeProtocolInfo
1152019-12-05 18:27:38.549 Detail, Node001, Notification: EssentialNodeQueriesComplete
1162019-12-05 18:27:38.549 Detail, Node001, Notification: ValueAdded
1172019-12-05 18:27:38.549 Detail, Node003, Notification: NodeNew
1182019-12-05 18:27:38.549 Detail, Node003, Notification: NodeAdded
1192019-12-05 18:27:38.550 Detail,
1202019-12-05 18:27:38.550 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
1212019-12-05 18:27:38.551 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
1222019-12-05 18:27:38.551 Detail,
1232019-12-05 18:27:38.551 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
1242019-12-05 18:27:38.551 Detail, Node015,   Expected reply was received
1252019-12-05 18:27:38.551 Detail, Node015,   Message transaction complete
1262019-12-05 18:27:38.551 Detail,
1272019-12-05 18:27:38.551 Detail, contrlr, Removing current message
1282019-12-05 18:27:38.551 Detail,
1292019-12-05 18:27:38.551 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
1302019-12-05 18:27:38.553 Detail, contrlr, Removing current message
1312019-12-05 18:27:38.553 Detail,
1322019-12-05 18:27:38.553 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
1332019-12-05 18:27:38.558 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x04, 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, 0x5a
1342019-12-05 18:27:38.558 Detail,
1352019-12-05 18:27:38.558 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
1362019-12-05 18:27:38.558 Info, Node001,     Neighbors of this node are:
1372019-12-05 18:27:38.558 Info, Node001,     Node 3
1382019-12-05 18:27:38.558 Detail,   Expected reply was received
1392019-12-05 18:27:38.558 Detail,   Message transaction complete
1402019-12-05 18:27:38.558 Detail,
1412019-12-05 18:27:38.558 Detail, Node001, Removing current message
1422019-12-05 18:27:38.558 Detail, Node001, Query Stage Complete (Neighbors)
1432019-12-05 18:27:38.558 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
1442019-12-05 18:27:38.558 Detail, Node001, QueryStage_Session
1452019-12-05 18:27:38.558 Detail, Node001, QueryStage_Dynamic
1462019-12-05 18:27:38.558 Detail, Node001, QueryStage_Configuration
1472019-12-05 18:27:38.558 Detail, Node001, QueryStage_Complete
1482019-12-05 18:27:38.559 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
1492019-12-05 18:27:38.559 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
1502019-12-05 18:27:38.559 Detail, Node001, Notification: NodeQueriesComplete
1512019-12-05 18:27:38.560 Detail,
1522019-12-05 18:27:38.560 Info, Node003, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=3): 0x01, 0x04, 0x00, 0x41, 0x03, 0xb9
1532019-12-05 18:27:38.562 Detail, Node003,   Received: 0x01, 0x09, 0x01, 0x41, 0x53, 0xdc, 0x01, 0x04, 0x40, 0x03, 0x7f
1542019-12-05 18:27:38.562 Detail,
1552019-12-05 18:27:38.562 Info, Node003, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
1562019-12-05 18:27:38.562 Info, Node003,   Protocol Info for Node 3:
1572019-12-05 18:27:38.562 Info, Node003,     Listening     = false
1582019-12-05 18:27:38.562 Info, Node003,     Frequent      = true
1592019-12-05 18:27:38.562 Info, Node003,     Beaming       = true
1602019-12-05 18:27:38.562 Info, Node003,     Routing       = true
1612019-12-05 18:27:38.562 Info, Node003,     Max Baud Rate = 40000
1622019-12-05 18:27:38.562 Info, Node003,     Version       = 4
1632019-12-05 18:27:38.562 Info, Node003,     Security      = false
1642019-12-05 18:27:38.567 Info, Node003,   Basic device class    (0x04) - Routing Slave
1652019-12-05 18:27:38.567 Info, Node003,   Generic device Class  (0x40) - Entry Control
1662019-12-05 18:27:38.567 Info, Node003,   Specific device class (0x03) - Secure Keypad Door Lock
1672019-12-05 18:27:38.568 Warning, Node003, Security Command Class Cannot be Enabled - NetworkKey is not set
1682019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_DOOR_LOCK
1692019-12-05 18:27:38.568 Info, Node003,   Mandatory Command Classes for Node 3:
1702019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_BASIC
1712019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_DOOR_LOCK
1722019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_USER_CODE
1732019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_MANUFACTURER_SPECIFIC
1742019-12-05 18:27:38.568 Info, Node003,     COMMAND_CLASS_VERSION
1752019-12-05 18:27:38.568 Info, Node003,   Mandatory Command Classes controlled by Node 3:
1762019-12-05 18:27:38.568 Info, Node003,     None
1772019-12-05 18:27:38.568 Detail, Node220,   Expected reply was received
1782019-12-05 18:27:38.568 Detail, Node220,   Message transaction complete
1792019-12-05 18:27:38.568 Detail,
1802019-12-05 18:27:38.568 Detail, Node003, Removing current message
1812019-12-05 18:27:38.568 Detail, Node003, Notification: NodeProtocolInfo
1822019-12-05 18:27:38.569 Detail, Node003, Notification: ValueAdded
1832019-12-05 18:27:38.570 Detail, Node003, Notification: ValueAdded
1842019-12-05 18:27:38.571 Detail, Node003, Notification: ValueAdded
1852019-12-05 18:27:38.571 Detail, Node003, Notification: ValueAdded
1862019-12-05 18:27:38.572 Detail, Node003, Notification: ValueAdded
1872019-12-05 18:27:38.572 Detail, Node003, Notification: ValueAdded
1882019-12-05 18:27:38.573 Detail, Node003, Notification: ValueAdded
1892019-12-05 18:27:38.573 Detail, Node003, Notification: ValueAdded
1902019-12-05 18:27:38.573 Detail, Node003, Notification: ValueAdded
1912019-12-05 18:27:38.575 Detail, Node003, Notification: ValueAdded
1922019-12-05 18:27:38.575 Detail, Node003, Query Stage Complete (ProtocolInfo)
1932019-12-05 18:27:38.575 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
1942019-12-05 18:27:38.575 Detail, Node003, QueryStage_Probe
1952019-12-05 18:27:38.575 Info, Node003, NoOperation::Set - Routing=true
1962019-12-05 18:27:38.575 Detail, Node003, Queuing (NoOp) NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcb
1972019-12-05 18:27:38.575 Detail, Node003, Queuing (Query) Query Stage Complete (Probe)
1982019-12-05 18:27:38.575 Detail,
1992019-12-05 18:27:38.576 Info, Node003, Sending (NoOp) message (Callback ID=0x0a, Expected Reply=0x13) - NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcb
2002019-12-05 18:27:38.581 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2012019-12-05 18:27:38.581 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2022019-12-05 18:27:39.885 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0a, 0x00, 0xe3
2032019-12-05 18:27:39.885 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2042019-12-05 18:27:39.885 Info, Node003, Request RTT 1309 Average Request RTT 1309
2052019-12-05 18:27:39.885 Detail,   Expected callbackId was received
2062019-12-05 18:27:39.885 Detail,   Expected reply was received
2072019-12-05 18:27:39.885 Detail,   Message transaction complete
2082019-12-05 18:27:39.885 Detail,
2092019-12-05 18:27:39.885 Detail, Node003, Removing current message
2102019-12-05 18:27:39.885 Detail, Node003, Notification: Notification - NoOperation
2112019-12-05 18:27:39.886 Detail, Node003, Query Stage Complete (Probe)
2122019-12-05 18:27:39.886 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1
2132019-12-05 18:27:39.886 Detail, Node003, QueryStage_WakeUp
2142019-12-05 18:27:39.886 Detail, Node003, QueryStage_ManufacturerSpecific1
2152019-12-05 18:27:39.886 Detail, Node003, Checking for ManufacturerSpecific CC and Requesting values if present on this node
2162019-12-05 18:27:39.886 Detail, Node003, Queuing (Query) ManufacturerSpecificCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x72, 0x04, 0x25, 0x0b, 0xbc
2172019-12-05 18:27:39.886 Detail, Node003, Queuing (Query) Query Stage Complete (ManufacturerSpecific1)
2182019-12-05 18:27:39.886 Detail,
2192019-12-05 18:27:39.886 Info, Node003, Sending (Query) message (Callback ID=0x0b, Expected Reply=0x04) - ManufacturerSpecificCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x72, 0x04, 0x25, 0x0b, 0xbc
2202019-12-05 18:27:39.891 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2212019-12-05 18:27:39.891 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2222019-12-05 18:27:39.910 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0b, 0x00, 0xe2
2232019-12-05 18:27:39.910 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2242019-12-05 18:27:39.910 Info, Node003, Request RTT 24 Average Request RTT 666
2252019-12-05 18:27:39.910 Detail,   Expected callbackId was received
2262019-12-05 18:27:40.254 Detail, Node003,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x03, 0x08, 0x72, 0x05, 0x01, 0x29, 0x80, 0x02, 0x06, 0x00, 0x25
2272019-12-05 18:27:40.254 Detail,
2282019-12-05 18:27:40.254 Info, Node003, Response RTT 369 Average Response RTT 369
2292019-12-05 18:27:40.286 Info, Product name collision: FGR223 Roller Shutter Controller 3 type 303 id 1000 manufacturerid 10f, collides with FGRM223 Roller Shutter Controller 3, type 303 id 1000 manufacturerid 10f
2302019-12-05 18:27:40.291 Info, Node003,   Opening config param file /usr/local/lib/python3.7/site-packages/python_openzwave/ozw_config/assa_abloy/TouchDeadbolt.xml
2312019-12-05 18:27:40.292 Info, Node003, Received manufacturer specific report from node 3: Manufacturer=Assa Abloy, Product=Yale Key Free Touchscreen Deadbolt (YRD446)
2322019-12-05 18:27:40.292 Info, Node003, Node Identity Codes: 0129:8002:0600
2332019-12-05 18:27:40.292 Detail, Node003,   Expected reply and command class was received
2342019-12-05 18:27:40.292 Detail, Node003,   Message transaction complete
2352019-12-05 18:27:40.292 Detail,
2362019-12-05 18:27:40.292 Detail, Node003, Removing current message
2372019-12-05 18:27:40.292 Detail, Node003, Notification: NodeNaming
2382019-12-05 18:27:40.292 Detail, Node003, Query Stage Complete (ManufacturerSpecific1)
2392019-12-05 18:27:40.292 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=NodeInfo live=1
2402019-12-05 18:27:40.292 Detail, Node003, QueryStage_NodeInfo
2412019-12-05 18:27:40.292 Detail, Node003, Queuing (Query) Request Node Info (Node=3): 0x01, 0x04, 0x00, 0x60, 0x03, 0x98
2422019-12-05 18:27:40.292 Detail, Node003, Queuing (Query) Query Stage Complete (NodeInfo)
2432019-12-05 18:27:40.292 Detail,
2442019-12-05 18:27:40.292 Info, Node003, Sending (Query) message (Callback ID=0x00, Expected Reply=0x49) - Request Node Info (Node=3): 0x01, 0x04, 0x00, 0x60, 0x03, 0x98
2452019-12-05 18:27:40.297 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x60, 0x01, 0x9b
2462019-12-05 18:27:40.297 Detail,
2472019-12-05 18:27:40.297 Info, Node155, FUNC_ID_ZW_REQUEST_NODE_INFO Request successful.
2482019-12-05 18:27:40.332 Detail, Node003,   Received: 0x01, 0x0f, 0x00, 0x49, 0x84, 0x03, 0x09, 0x04, 0x40, 0x03, 0x5e, 0x72, 0x98, 0x5a, 0x73, 0x86, 0x6b
2492019-12-05 18:27:40.332 Detail,
2502019-12-05 18:27:40.332 Info, Node003, UPDATE_STATE_NODE_INFO_RECEIVED from node 3
2512019-12-05 18:27:40.332 Info, Node003,   Optional command classes for node 3:
2522019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_ZWAVEPLUS_INFO
2532019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_MANUFACTURER_SPECIFIC (Existing)
2542019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_SECURITY (Disabled - Network Key Not Set)
2552019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_DEVICE_RESET_LOCALLY
2562019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_POWERLEVEL
2572019-12-05 18:27:40.332 Info, Node003,     COMMAND_CLASS_VERSION (Existing)
2582019-12-05 18:27:40.332 Detail, Node003, AdvanceQueries queryPending=1 queryRetries=0 queryStage=NodeInfo live=1
2592019-12-05 18:27:40.332 Detail, Node003,   Expected reply was received
2602019-12-05 18:27:40.332 Detail, Node003,   Message transaction complete
2612019-12-05 18:27:40.332 Detail,
2622019-12-05 18:27:40.332 Detail, Node003, Removing current message
2632019-12-05 18:27:40.333 Detail, Node003, Notification: ValueAdded
2642019-12-05 18:27:40.333 Detail, Node003, Notification: ValueAdded
2652019-12-05 18:27:40.333 Detail, Node003, Notification: ValueAdded
2662019-12-05 18:27:40.333 Detail, Node003, Notification: ValueAdded
2672019-12-05 18:27:40.334 Detail, Node003, Notification: ValueAdded
2682019-12-05 18:27:40.334 Detail, Node003, Notification: ValueAdded
2692019-12-05 18:27:40.334 Detail, Node003, Notification: ValueAdded
2702019-12-05 18:27:40.334 Detail, Node003, Notification: ValueAdded
2712019-12-05 18:27:40.335 Detail, Node003, Notification: ValueAdded
2722019-12-05 18:27:40.335 Detail, Node003, Notification: ValueAdded
2732019-12-05 18:27:40.335 Detail, Node003, Notification: ValueAdded
2742019-12-05 18:27:40.335 Detail, Node003, Notification: ValueAdded
2752019-12-05 18:27:40.336 Detail, Node003, Notification: ValueAdded
2762019-12-05 18:27:40.336 Detail, Node003, Query Stage Complete (NodeInfo)
2772019-12-05 18:27:40.337 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=NodePlusInfo live=1
2782019-12-05 18:27:40.337 Detail, Node003, QueryStage_NodePlusInfo
2792019-12-05 18:27:40.337 Detail, Node003, Queuing (Query) ZWavePlusInfoCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x5e, 0x01, 0x25, 0x0c, 0x92
2802019-12-05 18:27:40.337 Detail, Node003, Queuing (Query) Query Stage Complete (NodePlusInfo)
2812019-12-05 18:27:40.337 Detail,
2822019-12-05 18:27:40.337 Info, Node003, Sending (Query) message (Callback ID=0x0c, Expected Reply=0x04) - ZWavePlusInfoCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x5e, 0x01, 0x25, 0x0c, 0x92
2832019-12-05 18:27:40.342 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2842019-12-05 18:27:40.342 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2852019-12-05 18:27:40.361 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0c, 0x00, 0xe5
2862019-12-05 18:27:40.361 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2872019-12-05 18:27:40.362 Info, Node003, Request RTT 24 Average Request RTT 345
2882019-12-05 18:27:40.362 Detail,   Expected callbackId was received
2892019-12-05 18:27:40.376 Detail, Node003,   Received: 0x01, 0x0f, 0x00, 0x04, 0x00, 0x03, 0x09, 0x5e, 0x02, 0x01, 0x07, 0x00, 0x03, 0x00, 0x03, 0x00, 0xa4
2902019-12-05 18:27:40.376 Detail,
2912019-12-05 18:27:40.376 Info, Node003, Response RTT 39 Average Response RTT 204
2922019-12-05 18:27:40.376 Info, Node003, ZWave+ Info Received from Node 3
2932019-12-05 18:27:40.376 Info, Node003,   Zwave+ Node Type  (0x00) - Z-Wave+ node. Mandatory Command Classes:
2942019-12-05 18:27:40.376 Info, Node003,     NONE
2952019-12-05 18:27:40.376 Info, Node003,   Zwave+ Device Type  (0x300) - Door Lock Keypad. Mandatory Command Classes:
2962019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_DEVICE_RESET_LOCALLY
2972019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_ZWAVEPLUS_INFO
2982019-12-05 18:27:40.376 Info, Node003,     0x59 (Not Supported)
2992019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_MANUFACTURER_SPECIFIC
3002019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_POWERLEVEL
3012019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_ASSOCIATION
3022019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_VERSION
3032019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_DOOR_LOCK
3042019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_USER_CODE
3052019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_BATTERY
3062019-12-05 18:27:40.376 Info, Node003,   ZWave+ Role Type  (0x40) - Listening Sleeping Slave
3072019-12-05 18:27:40.376 Info, Node003,     COMMAND_CLASS_DEVICE_RESET_LOCALLY
3082019-12-05 18:27:40.376 Detail, Node003, Initial read of value
3092019-12-05 18:27:40.376 Detail, Node003, Initial read of value
3102019-12-05 18:27:40.376 Detail, Node003, Initial read of value
3112019-12-05 18:27:40.376 Detail, Node003,   Expected reply and command class was received
3122019-12-05 18:27:40.376 Detail, Node003,   Message transaction complete
3132019-12-05 18:27:40.376 Detail,
3142019-12-05 18:27:40.376 Detail, Node003, Removing current message
3152019-12-05 18:27:40.376 Detail, Node003, Notification: ValueAdded
3162019-12-05 18:27:40.378 Detail, Node003, Notification: ValueChanged
3172019-12-05 18:27:40.382 Detail, Node003, Notification: ValueChanged
3182019-12-05 18:27:40.383 Detail, Node003, Notification: ValueChanged
3192019-12-05 18:27:40.384 Detail, Node003, Query Stage Complete (NodePlusInfo)
3202019-12-05 18:27:40.384 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=SecurityReport live=1
3212019-12-05 18:27:40.384 Detail, Node003, QueryStage_SecurityReport
3222019-12-05 18:27:40.384 Info, Node003,   Opening config param file /usr/local/lib/python3.7/site-packages/python_openzwave/ozw_config/assa_abloy/TouchDeadbolt.xml
3232019-12-05 18:27:40.385 Info, Node003, Value Refresh triggered by CommandClass: COMMAND_CLASS_ALARM, Genre: 1, Instance: 1, Index: 0 for:
3242019-12-05 18:27:40.385 Info, Node003,     CommandClass: COMMAND_CLASS_DOOR_LOCK, RequestFlags: 0, Instance: 1, Index: 1
3252019-12-05 18:27:40.386 Detail, Node003, QueryStage_Versions
3262019-12-05 18:27:40.386 Info, Node003, Requesting Versions
3272019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_NO_OPERATION
3282019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_BASIC
3292019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_DEVICE_RESET_LOCALLY
3302019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_ZWAVEPLUS_INFO
3312019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_DOOR_LOCK
3322019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_USER_CODE
3332019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_CONFIGURATION
3342019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_ALARM
3352019-12-05 18:27:40.386 Info, Node003, 	ok
3362019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_MANUFACTURER_SPECIFIC
3372019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_POWERLEVEL
3382019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_BATTERY
3392019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_ASSOCIATION
3402019-12-05 18:27:40.386 Info, Node003, Requesting Versions for COMMAND_CLASS_VERSION
3412019-12-05 18:27:40.386 Detail, Node003, QueryStage_Instances
3422019-12-05 18:27:40.386 Info, Node003, Essential node queries are complete
3432019-12-05 18:27:40.386 Detail, Node003, QueryStage_Static
3442019-12-05 18:27:40.386 Detail, Node003, Queuing (Query) DoorLockCmd_Configuration_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x05, 0x25, 0x0d, 0xab
3452019-12-05 18:27:40.386 Detail, Node003, Queuing (Query) UserNumberCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x63, 0x04, 0x25, 0x0e, 0xa8
3462019-12-05 18:27:40.386 Detail, Node003, Queuing (Query) AssociationCmd_GroupingsGet (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x85, 0x05, 0x25, 0x0f, 0x4e
3472019-12-05 18:27:40.386 Detail, Node003, Queuing (Query) VersionCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x86, 0x11, 0x25, 0x10, 0x46
3482019-12-05 18:27:40.386 Detail, Node003, Queuing (Query) Query Stage Complete (Static)
3492019-12-05 18:27:40.386 Detail, Node003, Notification: ValueAdded
3502019-12-05 18:27:40.387 Detail, Node003, Notification: ValueAdded
3512019-12-05 18:27:40.388 Detail, Node003, Notification: ValueAdded
3522019-12-05 18:27:40.389 Detail, Node003, Notification: ValueAdded
3532019-12-05 18:27:40.390 Detail, Node003, Notification: ValueAdded
3542019-12-05 18:27:40.391 Detail, Node003, Notification: ValueAdded
3552019-12-05 18:27:40.391 Detail, Node003, Notification: ValueAdded
3562019-12-05 18:27:40.392 Detail, Node003, Notification: ValueAdded
3572019-12-05 18:27:40.392 Detail, Node003, Notification: ValueAdded
3582019-12-05 18:27:40.393 Detail, Node003, Notification: ValueAdded
3592019-12-05 18:27:40.393 Detail, Node003, Notification: ValueAdded
3602019-12-05 18:27:40.394 Detail, Node003, Notification: ValueAdded
3612019-12-05 18:27:40.397 Detail, Node003, Notification: ValueAdded
3622019-12-05 18:27:40.405 Detail, Node003, Notification: Group
3632019-12-05 18:27:40.405 Detail, Node003, Notification: EssentialNodeQueriesComplete
3642019-12-05 18:27:40.405 Detail,
3652019-12-05 18:27:40.406 Info, Node003, Sending (Query) message (Callback ID=0x0d, Expected Reply=0x04) - DoorLockCmd_Configuration_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x05, 0x25, 0x0d, 0xab
3662019-12-05 18:27:40.411 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
3672019-12-05 18:27:40.411 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
3682019-12-05 18:27:40.430 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0d, 0x00, 0xe4
3692019-12-05 18:27:40.430 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
3702019-12-05 18:27:40.430 Info, Node003, Request RTT 24 Average Request RTT 184
3712019-12-05 18:27:40.430 Detail,   Expected callbackId was received
3722019-12-05 18:27:50.406 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
3732019-12-05 18:27:50.406 Detail, Node003, Removing current message
3742019-12-05 18:27:50.406 Detail, Node003, Notification: Notification - TimeOut
3752019-12-05 18:27:50.407 Detail,
3762019-12-05 18:27:50.407 Info, Node003, Sending (Query) message (Callback ID=0x0e, Expected Reply=0x04) - UserNumberCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x63, 0x04, 0x25, 0x0e, 0xa8
3772019-12-05 18:27:50.413 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
3782019-12-05 18:27:50.413 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
3792019-12-05 18:27:51.717 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0e, 0x00, 0xe7
3802019-12-05 18:27:51.717 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
3812019-12-05 18:27:51.717 Info, Node003, Request RTT 1309 Average Request RTT 746
3822019-12-05 18:27:51.717 Detail,   Expected callbackId was received
3832019-12-05 18:28:00.408 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
3842019-12-05 18:28:00.408 Detail, Node003, Removing current message
3852019-12-05 18:28:00.408 Detail, Node003, Notification: Notification - TimeOut
3862019-12-05 18:28:00.408 Detail,
3872019-12-05 18:28:00.408 Info, Node003, Sending (Query) message (Callback ID=0x0f, Expected Reply=0x04) - AssociationCmd_GroupingsGet (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x85, 0x05, 0x25, 0x0f, 0x4e
3882019-12-05 18:28:00.414 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
3892019-12-05 18:28:00.414 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
3902019-12-05 18:28:01.703 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x0f, 0x00, 0xe6
3912019-12-05 18:28:01.703 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f)
3922019-12-05 18:28:01.703 Info, Node003, Request RTT 1294 Average Request RTT 1020
3932019-12-05 18:28:01.703 Detail,   Expected callbackId was received
3942019-12-05 18:28:10.409 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
3952019-12-05 18:28:10.409 Detail, Node003, Removing current message
3962019-12-05 18:28:10.409 Detail, Node003, Notification: Notification - TimeOut
3972019-12-05 18:28:10.410 Detail,
3982019-12-05 18:28:10.410 Info, Node003, Sending (Query) message (Callback ID=0x10, Expected Reply=0x04) - VersionCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x86, 0x11, 0x25, 0x10, 0x46
3992019-12-05 18:28:10.416 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
4002019-12-05 18:28:10.416 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
4012019-12-05 18:28:11.660 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x10, 0x00, 0xf9
4022019-12-05 18:28:11.660 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10)
4032019-12-05 18:28:11.660 Info, Node003, Request RTT 1249 Average Request RTT 1134
4042019-12-05 18:28:11.660 Detail,   Expected callbackId was received
4052019-12-05 18:28:12.120 Detail, Node003,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x03, 0x0b, 0x86, 0x12, 0x03, 0x04, 0x18, 0x01, 0x4e, 0xff, 0x01, 0x1b, 0x00, 0xc3
4062019-12-05 18:28:12.120 Detail,
4072019-12-05 18:28:12.120 Info, Node003, Response RTT 1710 Average Response RTT 957
4082019-12-05 18:28:12.120 Info, Node003, Received Version report from node 3: Library=3, Protocol=4.24, Application=1.78
4092019-12-05 18:28:12.120 Detail, Node003, Initial read of value
4102019-12-05 18:28:12.120 Detail, Node003, Initial read of value
4112019-12-05 18:28:12.120 Detail, Node003, Initial read of value
4122019-12-05 18:28:12.120 Detail, Node003,   Expected reply and command class was received
4132019-12-05 18:28:12.120 Detail, Node003,   Message transaction complete
4142019-12-05 18:28:12.120 Detail,
4152019-12-05 18:28:12.120 Detail, Node003, Removing current message
4162019-12-05 18:28:12.120 Detail, Node003, Notification: ValueChanged
4172019-12-05 18:28:12.121 Detail, Node003, Notification: ValueChanged
4182019-12-05 18:28:12.121 Detail, Node003, Notification: ValueChanged
4192019-12-05 18:28:12.121 Detail, Node003, Query Stage Complete (Static)
4202019-12-05 18:28:12.121 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
4212019-12-05 18:28:12.121 Detail, Node003, QueryStage_Associations
4222019-12-05 18:28:12.121 Info, Node003, Number of association groups reported for node 3 is 1.
4232019-12-05 18:28:12.121 Info, Node003, Get Associations for group 1 of node 3
4242019-12-05 18:28:12.121 Detail, Node003, Queuing (Send) AssociationCmd_Get (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x85, 0x02, 0x01, 0x25, 0x11, 0x54
4252019-12-05 18:28:12.121 Detail, Node003, Queuing (Query) Query Stage Complete (Associations)
4262019-12-05 18:28:12.121 Detail,
4272019-12-05 18:28:12.121 Info, Node003, Sending (Send) message (Callback ID=0x11, Expected Reply=0x04) - AssociationCmd_Get (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x85, 0x02, 0x01, 0x25, 0x11, 0x54
4282019-12-05 18:28:12.127 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
4292019-12-05 18:28:12.127 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
4302019-12-05 18:28:12.147 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x11, 0x00, 0xf8
4312019-12-05 18:28:12.147 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x11 received (expected 0x11)
4322019-12-05 18:28:12.147 Info, Node003, Request RTT 25 Average Request RTT 579
4332019-12-05 18:28:12.147 Detail,   Expected callbackId was received
4342019-12-05 18:28:22.122 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
4352019-12-05 18:28:22.122 Detail, Node003, Removing current message
4362019-12-05 18:28:22.122 Detail, Node003, Notification: Notification - TimeOut
4372019-12-05 18:28:22.123 Detail, Node003, Query Stage Complete (Associations)
4382019-12-05 18:28:22.123 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
4392019-12-05 18:28:22.123 Detail, Node003, QueryStage_Neighbors
4402019-12-05 18:28:22.123 Detail, Requesting routing info (neighbor list) for Node 3
4412019-12-05 18:28:22.123 Detail, Node003, Queuing (Command) Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
4422019-12-05 18:28:22.123 Detail, Node003, Queuing (Query) Query Stage Complete (Neighbors)
4432019-12-05 18:28:22.123 Detail,
4442019-12-05 18:28:22.123 Info, Node003, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
4452019-12-05 18:28:22.128 Detail, Node003,   Received: 0x01, 0x20, 0x01, 0x80, 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, 0x5f
4462019-12-05 18:28:22.128 Detail,
4472019-12-05 18:28:22.128 Info, Node003, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
4482019-12-05 18:28:22.128 Info, Node003,     Neighbors of this node are:
4492019-12-05 18:28:22.128 Info, Node003,     Node 1
4502019-12-05 18:28:22.128 Detail,   Expected reply was received
4512019-12-05 18:28:22.128 Detail,   Message transaction complete
4522019-12-05 18:28:22.128 Detail,
4532019-12-05 18:28:22.128 Detail, Node003, Removing current message
4542019-12-05 18:28:22.128 Detail, Node003, Query Stage Complete (Neighbors)
4552019-12-05 18:28:22.128 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
4562019-12-05 18:28:22.128 Detail, Node003, QueryStage_Session
4572019-12-05 18:28:22.128 Detail, Node003, Queuing (Query) PowerlevelCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x73, 0x02, 0x25, 0x12, 0xa2
4582019-12-05 18:28:22.128 Detail, Node003, Queuing (Query) Query Stage Complete (Session)
4592019-12-05 18:28:22.128 Detail,
4602019-12-05 18:28:22.128 Info, Node003, Sending (Query) message (Callback ID=0x12, Expected Reply=0x04) - PowerlevelCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x73, 0x02, 0x25, 0x12, 0xa2
4612019-12-05 18:28:22.134 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
4622019-12-05 18:28:22.134 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
4632019-12-05 18:28:23.408 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x12, 0x00, 0xfb
4642019-12-05 18:28:23.408 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x12 received (expected 0x12)
4652019-12-05 18:28:23.408 Info, Node003, Request RTT 1279 Average Request RTT 929
4662019-12-05 18:28:23.408 Detail,   Expected callbackId was received
4672019-12-05 18:28:23.420 Detail, Node003,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x03, 0x04, 0x73, 0x03, 0x00, 0x00, 0x86
4682019-12-05 18:28:23.420 Detail,
4692019-12-05 18:28:23.421 Info, Node003, Response RTT 1292 Average Response RTT 1124
4702019-12-05 18:28:23.421 Info, Node003, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
4712019-12-05 18:28:23.421 Detail, Node003, Initial read of value
4722019-12-05 18:28:23.421 Detail, Node003, Initial read of value
4732019-12-05 18:28:23.421 Detail, Node003,   Expected reply and command class was received
4742019-12-05 18:28:23.421 Detail, Node003,   Message transaction complete
4752019-12-05 18:28:23.421 Detail,
4762019-12-05 18:28:23.421 Detail, Node003, Removing current message
4772019-12-05 18:28:23.421 Detail, Node003, Notification: ValueChanged
4782019-12-05 18:28:23.421 Detail, Node003, Notification: ValueChanged
4792019-12-05 18:28:23.421 Detail, Node003, Query Stage Complete (Session)
4802019-12-05 18:28:23.421 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
4812019-12-05 18:28:23.421 Detail, Node003, QueryStage_Dynamic
4822019-12-05 18:28:23.421 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x13, 0xb2
4832019-12-05 18:28:23.421 Detail, Node003, Queuing (Send) AlarmCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x71, 0x04, 0x25, 0x14, 0xa0
4842019-12-05 18:28:23.421 Detail, Node003, Queuing (Send) BatteryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x80, 0x02, 0x25, 0x15, 0x56
4852019-12-05 18:28:23.421 Detail, Node003, Queuing (Query) Query Stage Complete (Dynamic)
4862019-12-05 18:28:23.421 Detail,
4872019-12-05 18:28:23.421 Info, Node003, Sending (Send) message (Callback ID=0x13, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x13, 0xb2
4882019-12-05 18:28:23.427 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
4892019-12-05 18:28:23.427 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
4902019-12-05 18:28:23.447 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x13, 0x00, 0xfa
4912019-12-05 18:28:23.447 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
4922019-12-05 18:28:23.447 Info, Node003, Request RTT 25 Average Request RTT 477
4932019-12-05 18:28:23.447 Detail,   Expected callbackId was received
4942019-12-05 18:28:33.422 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
4952019-12-05 18:28:33.422 Detail, Node003, Removing current message
4962019-12-05 18:28:33.422 Detail, Node003, Notification: Notification - TimeOut
4972019-12-05 18:28:33.423 Detail,
4982019-12-05 18:28:33.423 Info, Node003, Sending (Send) message (Callback ID=0x14, Expected Reply=0x04) - AlarmCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x71, 0x04, 0x25, 0x14, 0xa0
4992019-12-05 18:28:33.429 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5002019-12-05 18:28:33.429 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5012019-12-05 18:28:34.703 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x14, 0x00, 0xfd
5022019-12-05 18:28:34.703 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x14 received (expected 0x14)
5032019-12-05 18:28:34.703 Info, Node003, Request RTT 1279 Average Request RTT 878
5042019-12-05 18:28:34.703 Detail,   Expected callbackId was received
5052019-12-05 18:28:43.424 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5062019-12-05 18:28:43.424 Detail, Node003, Removing current message
5072019-12-05 18:28:43.424 Detail, Node003, Notification: Notification - TimeOut
5082019-12-05 18:28:43.424 Detail,
5092019-12-05 18:28:43.424 Info, Node003, Sending (Send) message (Callback ID=0x15, Expected Reply=0x04) - BatteryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x80, 0x02, 0x25, 0x15, 0x56
5102019-12-05 18:28:43.430 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5112019-12-05 18:28:43.430 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5122019-12-05 18:28:44.734 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x15, 0x00, 0xfc
5132019-12-05 18:28:44.734 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15)
5142019-12-05 18:28:44.734 Info, Node003, Request RTT 1309 Average Request RTT 1093
5152019-12-05 18:28:44.734 Detail,   Expected callbackId was received
5162019-12-05 18:28:53.425 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5172019-12-05 18:28:53.425 Detail, Node003, Removing current message
5182019-12-05 18:28:53.425 Detail, Node003, Notification: Notification - TimeOut
5192019-12-05 18:28:53.425 Detail, Node003, Query Stage Complete (Dynamic)
5202019-12-05 18:28:53.426 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
5212019-12-05 18:28:53.426 Detail, Node003, QueryStage_Configuration
5222019-12-05 18:28:53.426 Detail, Node003, QueryStage_Complete
5232019-12-05 18:28:53.426 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
5242019-12-05 18:28:53.426 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
5252019-12-05 18:28:53.426 Info,          Node query processing complete.
5262019-12-05 18:28:53.426 Detail, Node003, Notification: NodeQueriesComplete
5272019-12-05 18:28:53.426 Detail, contrlr, Notification: AllNodesQueried
5282019-12-05 18:29:18.502 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
5292019-12-05 18:29:18.502 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
5302019-12-05 18:29:18.502 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x16, 0xb6
5312019-12-05 18:29:18.502 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x17, 0xb6
5322019-12-05 18:29:18.502 Detail,
5332019-12-05 18:29:18.502 Info, Node003, Sending (Send) message (Callback ID=0x16, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x16, 0xb6
5342019-12-05 18:29:18.508 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5352019-12-05 18:29:18.508 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5362019-12-05 18:29:19.753 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x16, 0x00, 0xff
5372019-12-05 18:29:19.753 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x16 received (expected 0x16)
5382019-12-05 18:29:19.753 Info, Node003, Request RTT 1250 Average Request RTT 1171
5392019-12-05 18:29:19.753 Detail,   Expected callbackId was received
5402019-12-05 18:29:27.756 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
5412019-12-05 18:29:27.756 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
5422019-12-05 18:29:27.756 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x18, 0xb8
5432019-12-05 18:29:27.756 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x19, 0xb8
5442019-12-05 18:29:28.503 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5452019-12-05 18:29:28.503 Detail, Node003, Removing current message
5462019-12-05 18:29:28.503 Detail, Node003, Notification: Notification - TimeOut
5472019-12-05 18:29:28.503 Detail,
5482019-12-05 18:29:28.503 Info, Node003, Sending (Send) message (Callback ID=0x17, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x17, 0xb6
5492019-12-05 18:29:28.509 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5502019-12-05 18:29:28.509 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5512019-12-05 18:29:29.753 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x17, 0x00, 0xfe
5522019-12-05 18:29:29.753 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17)
5532019-12-05 18:29:29.753 Info, Node003, Request RTT 1249 Average Request RTT 1210
5542019-12-05 18:29:29.753 Detail,   Expected callbackId was received
5552019-12-05 18:29:38.504 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5562019-12-05 18:29:38.504 Detail, Node003, Removing current message
5572019-12-05 18:29:38.504 Detail, Node003, Notification: Notification - TimeOut
5582019-12-05 18:29:38.505 Detail,
5592019-12-05 18:29:38.505 Info, Node003, Sending (Send) message (Callback ID=0x18, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x18, 0xb8
5602019-12-05 18:29:38.510 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5612019-12-05 18:29:38.510 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5622019-12-05 18:29:39.800 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x18, 0x00, 0xf1
5632019-12-05 18:29:39.800 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x18 received (expected 0x18)
5642019-12-05 18:29:39.800 Info, Node003, Request RTT 1295 Average Request RTT 1252
5652019-12-05 18:29:39.800 Detail,   Expected callbackId was received
5662019-12-05 18:29:48.505 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5672019-12-05 18:29:48.505 Detail, Node003, Removing current message
5682019-12-05 18:29:48.505 Detail, Node003, Notification: Notification - TimeOut
5692019-12-05 18:29:48.505 Detail,
5702019-12-05 18:29:48.505 Info, Node003, Sending (Send) message (Callback ID=0x19, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x19, 0xb8
5712019-12-05 18:29:48.511 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5722019-12-05 18:29:48.511 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5732019-12-05 18:29:49.755 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x19, 0x00, 0xf0
5742019-12-05 18:29:49.755 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19)
5752019-12-05 18:29:49.755 Info, Node003, Request RTT 1249 Average Request RTT 1250
5762019-12-05 18:29:49.755 Detail,   Expected callbackId was received
5772019-12-05 18:29:58.506 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5782019-12-05 18:29:58.506 Detail, Node003, Removing current message
5792019-12-05 18:29:58.506 Detail, Node003, Notification: Notification - TimeOut
5802019-12-05 18:31:10.033 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - True
5812019-12-05 18:31:10.033 Info, Node003, Value_Lock::Set - Requesting lock to be Locked
5822019-12-05 18:31:10.033 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0xff, 0x25, 0x1a, 0x45
5832019-12-05 18:31:10.033 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1b, 0xba
5842019-12-05 18:31:10.033 Detail,
5852019-12-05 18:31:10.033 Info, Node003, Sending (Send) message (Callback ID=0x1a, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0xff, 0x25, 0x1a, 0x45
5862019-12-05 18:31:10.039 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5872019-12-05 18:31:10.039 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5882019-12-05 18:31:11.284 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x1a, 0x00, 0xf3
5892019-12-05 18:31:11.284 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a)
5902019-12-05 18:31:11.284 Info, Node003, Request RTT 1250 Average Request RTT 1250
5912019-12-05 18:31:11.284 Detail,   Expected callbackId was received
5922019-12-05 18:31:20.034 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
5932019-12-05 18:31:20.034 Detail, Node003, Removing current message
5942019-12-05 18:31:20.034 Detail, Node003, Notification: Notification - TimeOut
5952019-12-05 18:31:20.034 Detail,
5962019-12-05 18:31:20.034 Info, Node003, Sending (Send) message (Callback ID=0x1b, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1b, 0xba
5972019-12-05 18:31:20.040 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
5982019-12-05 18:31:20.040 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
5992019-12-05 18:31:21.299 Detail, Node003,   Received: 0x01, 0x05, 0x00, 0x13, 0x1b, 0x00, 0xf2
6002019-12-05 18:31:21.299 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0x1b received (expected 0x1b)
6012019-12-05 18:31:21.299 Info, Node003, Request RTT 1264 Average Request RTT 1257
6022019-12-05 18:31:21.299 Detail,   Expected callbackId was received