DEBUG 2020-03-01 14:43:10,999 (Z-Wave): Command_Builder : Building Z-Wave command DEBUG 2020-03-01 14:43:10,999 (Z-Wave): Command_Builder : Z-Wave command: nodeId = '1', endPoint = '0' DEBUG 2020-03-01 14:43:10,999 (Z-Wave): Command_Builder : Z-Wave command: command = SECURE_INCLUSION_MODE_ON DEBUG 2020-03-01 14:43:11,000 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:11,000 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:11,000 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:11,000 (Z-Wave): TX_Session_Layer : Sending Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' call. DEBUG 2020-03-01 14:43:11,000 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0xC1, 0x54, 0x25]. DEBUG 2020-03-01 14:43:11,001 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:11,001 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0xC1, 0x54, 0x25] transmission OK. DEBUG 2020-03-01 14:43:11,001 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' has been successfully sent --> waiting '10 000' [ms] for 'ADD_NODE_STATUS_LEARN_READY' callback. DEBUG 2020-03-01 14:43:11,003 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:11,003 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x54, 0x01, 0x00, 0x00, 0xE7] DEBUG 2020-03-01 14:43:11,003 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x54, 0x01, 0x00, 0x00, 0xE7] has been received. DEBUG 2020-03-01 14:43:11,003 (Z-Wave): RX_Session_Layer : Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' has received 'ADD_NODE_STATUS_LEARN_READY' callback --> waiting '60 000' [ms] for 'ADD_NODE_STATUS_NODE_FOUND' callback. DEBUG 2020-03-01 14:43:19,628 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:19,628 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x54, 0x02, 0x00, 0x00, 0xE4] DEBUG 2020-03-01 14:43:19,628 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x54, 0x02, 0x00, 0x00, 0xE4] has been received. DEBUG 2020-03-01 14:43:19,628 (Z-Wave): RX_Session_Layer : Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' has received 'ADD_NODE_STATUS_NODE_FOUND' callback frame [Status: ADD_NODE_STATUS_NODE_FOUND, Callback ID: 0x54, Node ID: 0, Device Classes: [Basic: 0x00, Generic: 0x00, Specific: 0x00], Command Classes: [Supported: [ -- ], Controlled: [ -- ]]] --> waiting '76 000' [ms] for 'ADD_NODE_STATUS_ADDING_SLAVE' or 'ADD_NODE_STATUS_ADDING_CONTROLLER' callback. DEBUG 2020-03-01 14:43:19,841 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:19,841 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x4A, 0x54, 0x03, 0x3F, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0x77] DEBUG 2020-03-01 14:43:19,841 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x4A, 0x54, 0x03, 0x3F, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0x77] has been received. DEBUG 2020-03-01 14:43:19,842 (Z-Wave): RX_Session_Layer : Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' has received 'ADD_NODE_STATUS_ADDING_SLAVE' callback frame [Status: ADD_NODE_STATUS_ADDING_SLAVE, Callback ID: 0x54, Node ID: 63, Device Classes: [Basic: 0x04, Generic: 0x40, Specific: 0x03], Command Classes: [Supported: [COMMAND_CLASS_ZWAVEPLUS_INFO, COMMAND_CLASS_SECURITY, 0x9F, COMMAND_CLASS_TRANSPORT_SERVICE, COMMAND_CLASS_MANUFACTURER_SPECIFIC, COMMAND_CLASS_TIME], Controlled: [ -- ]]] --> waiting for 'ADD_NODE_STATUS_PROTOCOL_DONE' callback. DEBUG 2020-03-01 14:43:39,160 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:39,160 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x54, 0x05, 0x3F, 0x00, 0xDC] DEBUG 2020-03-01 14:43:39,160 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x54, 0x05, 0x3F, 0x00, 0xDC] has been received. DEBUG 2020-03-01 14:43:39,160 (Z-Wave): RX_Session_Layer : Z-Wave function 'ZW_AddNodeToNetwork(Mode(s)=[ADD_NODE_ANY, ADD_NODE_OPTION_HIGH_POWER, ADD_NODE_OPTION_NETWORK_WIDE], CallbackID=0x54)' has received 'ADD_NODE_STATUS_PROTOCOL_DONE' callback frame [Status: ADD_NODE_STATUS_PROTOCOL_DONE, Callback ID: 0x54, Node ID: 63, Device Classes: [Basic: 0x00, Generic: 0x00, Specific: 0x00], Command Classes: [Supported: [ -- ], Controlled: [ -- ]]]. DEBUG 2020-03-01 14:43:39,160 (Z-Wave): TX_Session_Layer : Sending Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' call. DEBUG 2020-03-01 14:43:39,161 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x54, 0xE1]. DEBUG 2020-03-01 14:43:39,162 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:39,162 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x54, 0xE1] transmission OK. DEBUG 2020-03-01 14:43:39,162 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' has been successfully sent --> waiting '500' [ms] for 'ADD_NODE_STATUS_DONE' callback. DEBUG 2020-03-01 14:43:39,212 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:39,212 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] DEBUG 2020-03-01 14:43:39,212 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] has been received. DEBUG 2020-03-01 14:43:39,212 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' successfully executed. DEBUG 2020-03-01 14:43:39,213 (Z-Wave): TX_Session_Layer : Sending Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' call. DEBUG 2020-03-01 14:43:39,213 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x54, 0xE1]. DEBUG 2020-03-01 14:43:39,214 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:39,214 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x54, 0xE1] transmission OK. DEBUG 2020-03-01 14:43:39,214 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' has been successfully sent --> waiting '500' [ms] for 'ADD_NODE_STATUS_DONE' callback. DEBUG 2020-03-01 14:43:39,264 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:39,264 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] DEBUG 2020-03-01 14:43:39,264 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] has been received. DEBUG 2020-03-01 14:43:39,265 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x07, 0x00, 0x4A, 0x54, 0x06, 0x3F, 0x00, 0xDF] -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x54)' successfully executed. DEBUG 2020-03-01 14:43:39,265 (Z-Wave): Inclusion_Manager : Node '63' : [ZWAddNodeToNetwork, Status=ADD_NODE_STATUS_DONE, Supported= [COMMAND_CLASS_ZWAVEPLUS_INFO, COMMAND_CLASS_SECURITY, COMMAND_CLASS_UNKNOWN, COMMAND_CLASS_TRANSPORT_SERVICE, COMMAND_CLASS_MANUFACTURER_SPECIFIC, COMMAND_CLASS_TIME], Controlled=[]] DEBUG 2020-03-01 14:43:39,265 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_SCHEME_GET] DEBUG 2020-03-01 14:43:39,265 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:39,265 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:39,265 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:39,265 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x55)' request (callback ID=0x55). DEBUG 2020-03-01 14:43:39,265 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x3F, 0x03, 0x98, 0x04, 0x00, 0x05, 0x55, 0x16]. DEBUG 2020-03-01 14:43:39,267 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:39,267 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x3F, 0x03, 0x98, 0x04, 0x00, 0x05, 0x55, 0x16] transmission OK. DEBUG 2020-03-01 14:43:39,267 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x55)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:39,293 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:39,293 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:39,293 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:39,293 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x55). DEBUG 2020-03-01 14:43:39,293 (Z-Wave): RX_Session_Layer : Waiting 64 972 [ms] for 'ZW_SendData' callback frame (callback ID=0x55). DEBUG 2020-03-01 14:43:40,506 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:40,506 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x55, 0x00, 0xBC] DEBUG 2020-03-01 14:43:40,506 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x55, 0x00, 0xBC] has been received. DEBUG 2020-03-01 14:43:40,506 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x55, 0x00, 0xBC] (callback ID=0x55) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x55)' successfully executed. DEBUG 2020-03-01 14:43:40,506 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x55) DEBUG 2020-03-01 14:43:40,580 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:40,580 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x3F, 0x03, 0x98, 0x05, 0x00, 0x53] DEBUG 2020-03-01 14:43:40,580 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x3F, 0x03, 0x98, 0x05, 0x00, 0x53] has been received. DEBUG 2020-03-01 14:43:40,591 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_SCHEME_REPORT, Schemes= [SECURITY_SCHEME_0]] DEBUG 2020-03-01 14:43:40,591 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x55) DEBUG 2020-03-01 14:43:40,592 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_KEY_SET, Key=[0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08]] DEBUG 2020-03-01 14:43:40,599 (Z-Wave): Controller_API : Enqueue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:40,599 (Z-Wave): Controller_API : Dequeue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:40,599 (Z-Wave): CCSecurityFuncDecorator : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] DEBUG 2020-03-01 14:43:40,601 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:40,601 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:40,601 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:40,601 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x56)' request (callback ID=0x56). DEBUG 2020-03-01 14:43:40,601 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x56, 0x53]. DEBUG 2020-03-01 14:43:40,603 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:40,603 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x56, 0x53] transmission OK. DEBUG 2020-03-01 14:43:40,603 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x56)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:40,605 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:40,606 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:40,606 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:40,606 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x56). DEBUG 2020-03-01 14:43:40,606 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x56). DEBUG 2020-03-01 14:43:41,803 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:41,803 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x56, 0x00, 0xBF] DEBUG 2020-03-01 14:43:41,803 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x56, 0x00, 0xBF] has been received. DEBUG 2020-03-01 14:43:41,803 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x56, 0x00, 0xBF] (callback ID=0x56) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x56)' successfully executed. DEBUG 2020-03-01 14:43:41,803 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x56) DEBUG 2020-03-01 14:43:41,885 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:41,885 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0xDD, 0x34, 0xA0, 0xC2, 0xB6, 0xBF, 0x85, 0x2D, 0xEC] DEBUG 2020-03-01 14:43:41,885 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0xDD, 0x34, 0xA0, 0xC2, 0xB6, 0xBF, 0x85, 0x2D, 0xEC] has been received. DEBUG 2020-03-01 14:43:41,885 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x56) DEBUG 2020-03-01 14:43:41,885 (Z-Wave): CCSecurityFuncDecorator : Node '63' : Received [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[DD, 34, A0, C2, B6, BF, 85, 2D]] DEBUG 2020-03-01 14:43:41,885 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:41,885 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:41,885 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:41,885 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x57)' request (callback ID=0x57). DEBUG 2020-03-01 14:43:41,916 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x2D, 0x00, 0x13, 0x3F, 0x26, 0x98, 0x81, 0x1B, 0x19, 0xF9, 0xBE, 0x63, 0xA5, 0xAA, 0xCE, 0xA2, 0x44, 0x04, 0x0B, 0xDB, 0xE2, 0x0A, 0xC5, 0x41, 0x68, 0x90, 0xDD, 0x8E, 0xA0, 0xAF, 0x53, 0xA3, 0x1E, 0xB6, 0xDD, 0xE9, 0x50, 0x67, 0x8B, 0xC9, 0x58, 0x20, 0x01, 0x05, 0x57, 0xEE]. DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x2D, 0x00, 0x13, 0x3F, 0x26, 0x98, 0x81, 0x1B, 0x19, 0xF9, 0xBE, 0x63, 0xA5, 0xAA, 0xCE, 0xA2, 0x44, 0x04, 0x0B, 0xDB, 0xE2, 0x0A, 0xC5, 0x41, 0x68, 0x90, 0xDD, 0x8E, 0xA0, 0xAF, 0x53, 0xA3, 0x1E, 0xB6, 0xDD, 0xE9, 0x50, 0x67, 0x8B, 0xC9, 0x58, 0x20, 0x01, 0x05, 0x57, 0xEE] transmission OK. DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x57)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x57). DEBUG 2020-03-01 14:43:41,916 (Z-Wave): RX_Session_Layer : Waiting 64 969 [ms] for 'ZW_SendData' callback frame (callback ID=0x57). DEBUG 2020-03-01 14:43:46,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:46,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x57, 0x00, 0xBE] DEBUG 2020-03-01 14:43:46,750 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x57, 0x00, 0xBE] has been received. DEBUG 2020-03-01 14:43:46,750 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x57, 0x00, 0xBE] (callback ID=0x57) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x57)' successfully executed. DEBUG 2020-03-01 14:43:46,750 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x57) DEBUG 2020-03-01 14:43:46,820 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:46,820 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] DEBUG 2020-03-01 14:43:46,820 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] has been received. DEBUG 2020-03-01 14:43:46,820 (Z-Wave): RX_Session_Layer : Node '63' : [APP_CMD_HANDLER_FRAME : NodeID='63', Status= [RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x40', Parameters: [ - ]] DEBUG 2020-03-01 14:43:46,820 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:43:46,821 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[75, C1, 85, 1C, 09, 60, E8, EA]] DEBUG 2020-03-01 14:43:46,822 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:46,822 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:46,823 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:46,823 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x58)' request (callback ID=0x58). DEBUG 2020-03-01 14:43:46,823 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA, 0x05, 0x58, 0xCB]. DEBUG 2020-03-01 14:43:46,825 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:46,825 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA, 0x05, 0x58, 0xCB] transmission OK. DEBUG 2020-03-01 14:43:46,825 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x58)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:46,827 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:46,827 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:46,827 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:46,827 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x58). DEBUG 2020-03-01 14:43:46,827 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x58). DEBUG 2020-03-01 14:43:48,578 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:48,578 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x58, 0x00, 0xB1] DEBUG 2020-03-01 14:43:48,578 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x58, 0x00, 0xB1] has been received. DEBUG 2020-03-01 14:43:48,578 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x58, 0x00, 0xB1] (callback ID=0x58) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x75, 0xC1, 0x85, 0x1C, 0x09, 0x60, 0xE8, 0xEA], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x58)' successfully executed. DEBUG 2020-03-01 14:43:48,579 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[75, C1, 85, 1C, 09, 60, E8, EA]] succeeded DEBUG 2020-03-01 14:43:48,661 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:48,661 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x1C, 0x00, 0x04, 0x00, 0x3F, 0x16, 0x98, 0x81, 0x36, 0x6B, 0x94, 0x98, 0x02, 0x38, 0x10, 0x8E, 0x3F, 0xE2, 0xE5, 0x75, 0xEE, 0x48, 0xBA, 0x8C, 0x06, 0xD5, 0x11, 0x46, 0x7B] DEBUG 2020-03-01 14:43:48,661 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x1C, 0x00, 0x04, 0x00, 0x3F, 0x16, 0x98, 0x81, 0x36, 0x6B, 0x94, 0x98, 0x02, 0x38, 0x10, 0x8E, 0x3F, 0xE2, 0xE5, 0x75, 0xEE, 0x48, 0xBA, 0x8C, 0x06, 0xD5, 0x11, 0x46, 0x7B] has been received. DEBUG 2020-03-01 14:43:48,662 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_MESSAGE_ENCAPSULATION] --> Decrypted : [APP_CMD_HANDLER_FRAME : NodeID='63', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x07', Parameters: [ - ]]. DEBUG 2020-03-01 14:43:48,662 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_KEY_VERIFY] --> SECURITY_KEY_SET succeeded. DEBUG 2020-03-01 14:43:48,662 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_COMMAND_SUPPORTED_GET] DEBUG 2020-03-01 14:43:48,662 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x06, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x57) DEBUG 2020-03-01 14:43:48,664 (Z-Wave): Controller_API : Dequeue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:48,664 (Z-Wave): Controller_API : Enqueue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:48,664 (Z-Wave): CCSecurityFuncDecorator : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] DEBUG 2020-03-01 14:43:48,664 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:48,664 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:48,664 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:48,664 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x59)' request (callback ID=0x59). DEBUG 2020-03-01 14:43:48,664 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x59, 0x5C]. DEBUG 2020-03-01 14:43:48,666 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:48,666 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x59, 0x5C] transmission OK. DEBUG 2020-03-01 14:43:48,666 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x59)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:48,668 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:48,668 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:48,668 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:48,668 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x59). DEBUG 2020-03-01 14:43:48,668 (Z-Wave): RX_Session_Layer : Waiting 64 997 [ms] for 'ZW_SendData' callback frame (callback ID=0x59). DEBUG 2020-03-01 14:43:49,888 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:49,888 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x59, 0x00, 0xB0] DEBUG 2020-03-01 14:43:49,888 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x59, 0x00, 0xB0] has been received. DEBUG 2020-03-01 14:43:49,888 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x59, 0x00, 0xB0] (callback ID=0x59) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x59)' successfully executed. DEBUG 2020-03-01 14:43:49,888 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x59) DEBUG 2020-03-01 14:43:49,959 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:49,959 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x65, 0x04, 0xA6, 0x67, 0xEA, 0xB7, 0x8B, 0x89, 0x39] DEBUG 2020-03-01 14:43:49,959 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x65, 0x04, 0xA6, 0x67, 0xEA, 0xB7, 0x8B, 0x89, 0x39] has been received. DEBUG 2020-03-01 14:43:49,959 (Z-Wave): CCSecurityFuncDecorator : Node '63' : Received [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[65, 04, A6, 67, EA, B7, 8B, 89]] DEBUG 2020-03-01 14:43:49,959 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:49,959 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:49,959 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x59) DEBUG 2020-03-01 14:43:49,959 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:49,959 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5A)' request (callback ID=0x5A). DEBUG 2020-03-01 14:43:49,959 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x1D, 0x00, 0x13, 0x3F, 0x16, 0x98, 0x81, 0x76, 0xA2, 0x6D, 0x4A, 0x4A, 0x9C, 0xA2, 0xE8, 0x7D, 0xA1, 0x90, 0x65, 0x33, 0x8B, 0x08, 0xB4, 0x80, 0x6E, 0xE0, 0xAC, 0x05, 0x5A, 0x7E]. DEBUG 2020-03-01 14:43:49,968 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:49,968 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x1D, 0x00, 0x13, 0x3F, 0x16, 0x98, 0x81, 0x76, 0xA2, 0x6D, 0x4A, 0x4A, 0x9C, 0xA2, 0xE8, 0x7D, 0xA1, 0x90, 0x65, 0x33, 0x8B, 0x08, 0xB4, 0x80, 0x6E, 0xE0, 0xAC, 0x05, 0x5A, 0x7E] transmission OK. DEBUG 2020-03-01 14:43:49,968 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5A)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:49,970 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:49,970 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:49,970 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:49,970 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5A). DEBUG 2020-03-01 14:43:49,970 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x5A). DEBUG 2020-03-01 14:43:51,200 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:51,200 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5A, 0x00, 0xB3] DEBUG 2020-03-01 14:43:51,200 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5A, 0x00, 0xB3] has been received. DEBUG 2020-03-01 14:43:51,200 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5A, 0x00, 0xB3] (callback ID=0x5A) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5A)' successfully executed. DEBUG 2020-03-01 14:43:51,200 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5A) DEBUG 2020-03-01 14:43:51,266 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:51,266 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] DEBUG 2020-03-01 14:43:51,266 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] has been received. DEBUG 2020-03-01 14:43:51,266 (Z-Wave): RX_Session_Layer : Node '63' : [APP_CMD_HANDLER_FRAME : NodeID='63', Status= [RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x40', Parameters: [ - ]] DEBUG 2020-03-01 14:43:51,266 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:43:51,266 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[13, CD, C8, 02, 79, A5, 3D, 22]] DEBUG 2020-03-01 14:43:51,266 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:51,266 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:51,266 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:51,266 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5B)' request (callback ID=0x5B). DEBUG 2020-03-01 14:43:51,266 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22, 0x05, 0x5B, 0x59]. DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22, 0x05, 0x5B, 0x59] transmission OK. DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5B)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5B). DEBUG 2020-03-01 14:43:51,282 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x5B). DEBUG 2020-03-01 14:43:52,519 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:52,519 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5B, 0x00, 0xB2] DEBUG 2020-03-01 14:43:52,519 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5B, 0x00, 0xB2] has been received. DEBUG 2020-03-01 14:43:52,519 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5B, 0x00, 0xB2] (callback ID=0x5B) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x13, 0xCD, 0xC8, 0x02, 0x79, 0xA5, 0x3D, 0x22], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5B)' successfully executed. DEBUG 2020-03-01 14:43:52,520 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[13, CD, C8, 02, 79, A5, 3D, 22]] succeeded DEBUG 2020-03-01 14:43:52,608 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:52,609 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x2B, 0x00, 0x04, 0x00, 0x3F, 0x25, 0x98, 0x81, 0xED, 0xCC, 0xF9, 0xC8, 0x39, 0x7E, 0x92, 0x57, 0x8A, 0x46, 0xFB, 0x25, 0x44, 0xE8, 0xE0, 0xEE, 0x8D, 0x7D, 0xE3, 0x06, 0xFC, 0x72, 0x16, 0x60, 0x96, 0x57, 0x13, 0xCF, 0x6D, 0xF1, 0x3A, 0x3A, 0xA1, 0x0A, 0x5E, 0x68] DEBUG 2020-03-01 14:43:52,609 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x2B, 0x00, 0x04, 0x00, 0x3F, 0x25, 0x98, 0x81, 0xED, 0xCC, 0xF9, 0xC8, 0x39, 0x7E, 0x92, 0x57, 0x8A, 0x46, 0xFB, 0x25, 0x44, 0xE8, 0xE0, 0xEE, 0x8D, 0x7D, 0xE3, 0x06, 0xFC, 0x72, 0x16, 0x60, 0x96, 0x57, 0x13, 0xCF, 0x6D, 0xF1, 0x3A, 0x3A, 0xA1, 0x0A, 0x5E, 0x68] has been received. DEBUG 2020-03-01 14:43:52,609 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_MESSAGE_ENCAPSULATION] --> Decrypted : [APP_CMD_HANDLER_FRAME : NodeID='63', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x03', Parameters: [0x00, 0x62, 0x86, 0x71, 0x85, 0x59, 0x6C, 0x73, 0x5A, 0x80, 0x7A, 0x70, 0x63, 0x8B, 0x4E]]. DEBUG 2020-03-01 14:43:52,612 (Z-Wave): Secure_Inclusion_Manager : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_COMMAND_SUPPORTED_REPORT, Supported=[COMMAND_CLASS_NO_OPERATION, COMMAND_CLASS_DOOR_LOCK, COMMAND_CLASS_VERSION, COMMAND_CLASS_ALARM, COMMAND_CLASS_ASSOCIATION, COMMAND_CLASS_ASSOCIATION_GRP_INFO, COMMAND_CLASS_POWERLEVEL, COMMAND_CLASS_DEVICE_RESET_LOCALLY, COMMAND_CLASS_BATTERY, COMMAND_CLASS_FIRMWARE_UPDATE_MD, COMMAND_CLASS_CONFIGURATION, COMMAND_CLASS_USER_CODE, COMMAND_CLASS_TIME_PARAMETERS, COMMAND_CLASS_SCHEDULE_ENTRY_LOCK], Controlled=[]] DEBUG 2020-03-01 14:43:52,612 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5A) INFO 2020-03-01 14:43:52,613 (Z-Wave): Inclusion_Manager : Node '63' : Z-Wave node has been successfully added to the *SECURE* Z-Wave network. DEBUG 2020-03-01 14:43:52,626 (Z-Wave): Controller : Retrieving Z-Wave node info (ID=63)... DEBUG 2020-03-01 14:43:52,626 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'. DEBUG 2020-03-01 14:43:52,626 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'. DEBUG 2020-03-01 14:43:52,626 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'... DEBUG 2020-03-01 14:43:52,626 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x41, 0x3F, 0x85]. DEBUG 2020-03-01 14:43:52,627 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:52,627 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x3F, 0x85] transmission OK. DEBUG 2020-03-01 14:43:52,627 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=63)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:52,628 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:52,628 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] DEBUG 2020-03-01 14:43:52,628 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] has been received. DEBUG 2020-03-01 14:43:52,628 (Z-Wave): RX_Session_Layer : Response received [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=63)' successfully executed. INFO 2020-03-01 14:43:52,629 (Z-Wave): Controller : Z-Wave node info (ID=63) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_ENTRY_CONTROL','SPECIFIC_TYPE_SECURE_KEYPAD_DOOR_LOCK'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING'],[Security : 'NODEINFO_SECURITY_FLIRS_WAKEUP_1000','NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']]. DEBUG 2020-03-01 14:43:52,631 (Z-Wave): Node_Initializer : Node '63' : [ZW_RequestNodeInfo, (Retry='1')] DEBUG 2020-03-01 14:43:52,631 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_RequestNodeInfo (NodeID=63)'. DEBUG 2020-03-01 14:43:52,631 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_RequestNodeInfo(NodeID=63)'... DEBUG 2020-03-01 14:43:52,631 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_RequestNodeInfo (NodeID=63)'. DEBUG 2020-03-01 14:43:52,631 (Z-Wave): ZW_RequestNodeInfo : Sending Z-Wave function 'ZW_RequestNodeInfo(NodeID=63)' call. DEBUG 2020-03-01 14:43:52,631 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x60, 0x3F, 0xA4]. DEBUG 2020-03-01 14:43:52,632 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:52,632 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x60, 0x3F, 0xA4] transmission OK. DEBUG 2020-03-01 14:43:52,633 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=63)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:52,636 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:52,636 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x60, 0x01, 0x9B] DEBUG 2020-03-01 14:43:52,636 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x60, 0x01, 0x9B] has been received. DEBUG 2020-03-01 14:43:52,636 (Z-Wave): RX_Session_Layer : Response received [0x01, 0x04, 0x01, 0x60, 0x01, 0x9B] -> Waiting 65 000 [ms] for callback of Z-Wave function call 'ZW_RequestNodeInfo(NodeID=63)'. DEBUG 2020-03-01 14:43:53,915 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:53,915 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x49, 0x84, 0x3F, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xB8] DEBUG 2020-03-01 14:43:53,915 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x49, 0x84, 0x3F, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xB8] has been received. DEBUG 2020-03-01 14:43:53,915 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x0F, 0x00, 0x49, 0x84, 0x3F, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xB8] -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=63)' successfully executed. DEBUG 2020-03-01 14:43:53,916 (Z-Wave): Node_Initializer : Node '63' : [ZW_RequestNodeInfo, (Retry='1')] succeeded : [[Status: UPDATE_STATE_NODE_INFO_RECEIVED, Node ID: 63, Device Classes: [Basic: BASIC_TYPE_ROUTING_SLAVE, Generic: GENERIC_TYPE_ENTRY_CONTROL, Specific: SPECIFIC_TYPE_SECURE_KEYPAD_DOOR_LOCK], Command Classes: [Supported: [COMMAND_CLASS_ZWAVEPLUS_INFO, COMMAND_CLASS_SECURITY, 0x9F, COMMAND_CLASS_TRANSPORT_SERVICE, COMMAND_CLASS_MANUFACTURER_SPECIFIC, COMMAND_CLASS_TIME], Controlled: [ -- ]]]]. DEBUG 2020-03-01 14:43:53,916 (Z-Wave): Node_Initializer : Node '63' : [ZW_GetNodeProtocolInfo]... DEBUG 2020-03-01 14:43:53,916 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'. DEBUG 2020-03-01 14:43:53,916 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'. DEBUG 2020-03-01 14:43:53,916 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=63)'... DEBUG 2020-03-01 14:43:53,916 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x41, 0x3F, 0x85]. DEBUG 2020-03-01 14:43:53,917 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:53,917 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x3F, 0x85] transmission OK. DEBUG 2020-03-01 14:43:53,917 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=63)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:53,918 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:53,918 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] DEBUG 2020-03-01 14:43:53,918 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] has been received. DEBUG 2020-03-01 14:43:53,918 (Z-Wave): RX_Session_Layer : Response received [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=63)' successfully executed. DEBUG 2020-03-01 14:43:53,919 (Z-Wave): Node_Initializer : Node '63' : [ZW_GetNodeProtocolInfo] : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_ENTRY_CONTROL','SPECIFIC_TYPE_SECURE_KEYPAD_DOOR_LOCK'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING'],[Security : 'NODEINFO_SECURITY_FLIRS_WAKEUP_1000','NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']]. DEBUG 2020-03-01 14:43:53,919 (Z-Wave): Node_Initializer : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_COMMAND_SUPPORTED_GET, (Retry='1')] DEBUG 2020-03-01 14:43:53,920 (Z-Wave): Controller_API : Dequeue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:53,920 (Z-Wave): Controller_API : Enqueue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:53,920 (Z-Wave): CCSecurityFuncDecorator : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] DEBUG 2020-03-01 14:43:53,920 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:53,920 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:53,920 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:53,920 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5C)' request (callback ID=0x5C). DEBUG 2020-03-01 14:43:53,920 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x5C, 0x59]. DEBUG 2020-03-01 14:43:53,921 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:53,921 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x5C, 0x59] transmission OK. DEBUG 2020-03-01 14:43:53,921 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5C)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:53,923 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:53,923 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:53,923 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:53,924 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5C). DEBUG 2020-03-01 14:43:53,924 (Z-Wave): RX_Session_Layer : Waiting 64 997 [ms] for 'ZW_SendData' callback frame (callback ID=0x5C). DEBUG 2020-03-01 14:43:55,146 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:55,146 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5C, 0x00, 0xB5] DEBUG 2020-03-01 14:43:55,146 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5C, 0x00, 0xB5] has been received. DEBUG 2020-03-01 14:43:55,146 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5C, 0x00, 0xB5] (callback ID=0x5C) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5C)' successfully executed. DEBUG 2020-03-01 14:43:55,146 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5C) DEBUG 2020-03-01 14:43:55,224 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:55,224 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x44, 0x19, 0x50, 0xF7, 0x9E, 0x9F, 0x4B, 0xAC, 0xDA] DEBUG 2020-03-01 14:43:55,224 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x44, 0x19, 0x50, 0xF7, 0x9E, 0x9F, 0x4B, 0xAC, 0xDA] has been received. DEBUG 2020-03-01 14:43:55,224 (Z-Wave): CCSecurityFuncDecorator : Node '63' : Received [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[44, 19, 50, F7, 9E, 9F, 4B, AC]] DEBUG 2020-03-01 14:43:55,224 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5C) DEBUG 2020-03-01 14:43:55,224 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:55,224 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:55,225 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:55,225 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D)' request (callback ID=0x5D). DEBUG 2020-03-01 14:43:55,225 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x1D, 0x00, 0x13, 0x3F, 0x16, 0x98, 0x81, 0x2D, 0xCD, 0x06, 0x33, 0x1D, 0xEF, 0xF0, 0xCB, 0xB1, 0x1F, 0xA0, 0x44, 0x97, 0x16, 0xCA, 0xAC, 0xBD, 0x79, 0x6B, 0x4B, 0x05, 0x5D, 0xCC]. DEBUG 2020-03-01 14:43:55,228 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:55,228 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x1D, 0x00, 0x13, 0x3F, 0x16, 0x98, 0x81, 0x2D, 0xCD, 0x06, 0x33, 0x1D, 0xEF, 0xF0, 0xCB, 0xB1, 0x1F, 0xA0, 0x44, 0x97, 0x16, 0xCA, 0xAC, 0xBD, 0x79, 0x6B, 0x4B, 0x05, 0x5D, 0xCC] transmission OK. DEBUG 2020-03-01 14:43:55,228 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:55,230 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:55,230 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:55,230 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:55,230 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5D). DEBUG 2020-03-01 14:43:55,230 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x5D). DEBUG 2020-03-01 14:43:56,464 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:56,464 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5D, 0x00, 0xB4] DEBUG 2020-03-01 14:43:56,464 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5D, 0x00, 0xB4] has been received. DEBUG 2020-03-01 14:43:56,464 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5D, 0x00, 0xB4] (callback ID=0x5D) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x02], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D)' successfully executed. DEBUG 2020-03-01 14:43:56,464 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D) DEBUG 2020-03-01 14:43:56,539 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:56,540 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] DEBUG 2020-03-01 14:43:56,540 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] has been received. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): RX_Session_Layer : Node '63' : [APP_CMD_HANDLER_FRAME : NodeID='63', Status= [RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x40', Parameters: [ - ]] DEBUG 2020-03-01 14:43:56,540 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[24, 7A, D3, D9, 90, 76, 77, 87]] DEBUG 2020-03-01 14:43:56,540 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:56,540 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[F0, 78, 9D, 1F, 8A, 9C, 2E, C5]] DEBUG 2020-03-01 14:43:56,540 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E)' request (callback ID=0x5E). DEBUG 2020-03-01 14:43:56,540 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:56,540 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87, 0x05, 0x5E, 0xC9]. DEBUG 2020-03-01 14:43:56,543 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:56,543 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87, 0x05, 0x5E, 0xC9] transmission OK. DEBUG 2020-03-01 14:43:56,543 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:56,545 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:56,545 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:56,545 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:56,545 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5E). DEBUG 2020-03-01 14:43:56,546 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x5E). DEBUG 2020-03-01 14:43:57,781 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:57,781 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5E, 0x00, 0xB7] DEBUG 2020-03-01 14:43:57,781 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5E, 0x00, 0xB7] has been received. DEBUG 2020-03-01 14:43:57,781 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5E, 0x00, 0xB7] (callback ID=0x5E) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x24, 0x7A, 0xD3, 0xD9, 0x90, 0x76, 0x77, 0x87], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E)' successfully executed. DEBUG 2020-03-01 14:43:57,781 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[24, 7A, D3, D9, 90, 76, 77, 87]] succeeded DEBUG 2020-03-01 14:43:57,781 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:57,781 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:57,781 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F)' request (callback ID=0x5F). DEBUG 2020-03-01 14:43:57,781 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5, 0x05, 0x5F, 0x7D]. DEBUG 2020-03-01 14:43:57,783 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:57,784 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5, 0x05, 0x5F, 0x7D] transmission OK. DEBUG 2020-03-01 14:43:57,784 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:57,787 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:57,787 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:57,787 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:57,787 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x5F). DEBUG 2020-03-01 14:43:57,787 (Z-Wave): RX_Session_Layer : Waiting 64 994 [ms] for 'ZW_SendData' callback frame (callback ID=0x5F). DEBUG 2020-03-01 14:43:58,973 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:58,973 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5F, 0x00, 0xB6] DEBUG 2020-03-01 14:43:58,973 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5F, 0x00, 0xB6] has been received. DEBUG 2020-03-01 14:43:58,973 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5F, 0x00, 0xB6] (callback ID=0x5F) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0xF0, 0x78, 0x9D, 0x1F, 0x8A, 0x9C, 0x2E, 0xC5], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F)' successfully executed. DEBUG 2020-03-01 14:43:58,973 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[F0, 78, 9D, 1F, 8A, 9C, 2E, C5]] succeeded DEBUG 2020-03-01 14:43:59,069 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:59,070 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x2B, 0x00, 0x04, 0x00, 0x3F, 0x25, 0x98, 0x81, 0xD4, 0x92, 0x5A, 0xF3, 0x20, 0xFC, 0x5A, 0xF8, 0x0B, 0x25, 0x10, 0x85, 0x2C, 0x95, 0x1C, 0x44, 0x47, 0x76, 0x95, 0x90, 0x1E, 0x56, 0x35, 0xB8, 0x97, 0xBE, 0x24, 0xA4, 0xFA, 0xD6, 0x32, 0xAA, 0x2A, 0x01, 0x16, 0xC9] DEBUG 2020-03-01 14:43:59,070 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x2B, 0x00, 0x04, 0x00, 0x3F, 0x25, 0x98, 0x81, 0xD4, 0x92, 0x5A, 0xF3, 0x20, 0xFC, 0x5A, 0xF8, 0x0B, 0x25, 0x10, 0x85, 0x2C, 0x95, 0x1C, 0x44, 0x47, 0x76, 0x95, 0x90, 0x1E, 0x56, 0x35, 0xB8, 0x97, 0xBE, 0x24, 0xA4, 0xFA, 0xD6, 0x32, 0xAA, 0x2A, 0x01, 0x16, 0xC9] has been received. DEBUG 2020-03-01 14:43:59,070 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_MESSAGE_ENCAPSULATION] --> Decrypted : [APP_CMD_HANDLER_FRAME : NodeID='63', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x03', Parameters: [0x00, 0x62, 0x86, 0x71, 0x85, 0x59, 0x6C, 0x73, 0x5A, 0x80, 0x7A, 0x70, 0x63, 0x8B, 0x4E]]. DEBUG 2020-03-01 14:43:59,071 (Z-Wave): Node_Initializer : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_COMMAND_SUPPORTED_REPORT (Retry='1'), Supported=[COMMAND_CLASS_NO_OPERATION, COMMAND_CLASS_DOOR_LOCK, COMMAND_CLASS_VERSION, COMMAND_CLASS_ALARM, COMMAND_CLASS_ASSOCIATION, COMMAND_CLASS_ASSOCIATION_GRP_INFO, COMMAND_CLASS_POWERLEVEL, COMMAND_CLASS_DEVICE_RESET_LOCALLY, COMMAND_CLASS_BATTERY, COMMAND_CLASS_FIRMWARE_UPDATE_MD, COMMAND_CLASS_CONFIGURATION, COMMAND_CLASS_USER_CODE, COMMAND_CLASS_TIME_PARAMETERS, COMMAND_CLASS_SCHEDULE_ENTRY_LOCK], Controlled=[]] DEBUG 2020-03-01 14:43:59,071 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D) DEBUG 2020-03-01 14:43:59,077 (Z-Wave): Node_Initializer : Node '63' : [COMMAND_CLASS_VERSION::VERSION_COMMAND_CLASS_GET (Retry='1'), CommandClass='COMMAND_CLASS_ZWAVEPLUS_INFO']... DEBUG 2020-03-01 14:43:59,079 (Z-Wave): Controller_API : Dequeue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:59,079 (Z-Wave): Controller_API : Enqueue (SECURE-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:59,079 (Z-Wave): CCSecurityFuncDecorator : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] DEBUG 2020-03-01 14:43:59,079 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:59,079 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:43:59,079 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:43:59,079 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60)' request (callback ID=0x60). DEBUG 2020-03-01 14:43:59,079 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x60, 0x65]. DEBUG 2020-03-01 14:43:59,082 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:43:59,082 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x3F, 0x02, 0x98, 0x40, 0x05, 0x60, 0x65] transmission OK. DEBUG 2020-03-01 14:43:59,082 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:43:59,085 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:43:59,085 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:43:59,085 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:43:59,085 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x60). DEBUG 2020-03-01 14:43:59,085 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x60). DEBUG 2020-03-01 14:44:00,299 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:00,300 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x60, 0x00, 0x89] DEBUG 2020-03-01 14:44:00,300 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x60, 0x00, 0x89] has been received. DEBUG 2020-03-01 14:44:00,300 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x60, 0x00, 0x89] (callback ID=0x60) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x40], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60)' successfully executed. DEBUG 2020-03-01 14:44:00,300 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60) DEBUG 2020-03-01 14:44:00,374 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:00,374 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x55, 0x14, 0x5E, 0x35, 0x41, 0xAB, 0xD0, 0x1E, 0xC8] DEBUG 2020-03-01 14:44:00,374 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x3F, 0x0A, 0x98, 0x80, 0x55, 0x14, 0x5E, 0x35, 0x41, 0xAB, 0xD0, 0x1E, 0xC8] has been received. DEBUG 2020-03-01 14:44:00,375 (Z-Wave): CCSecurityFuncDecorator : Node '63' : Received [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[55, 14, 5E, 35, 41, AB, D0, 1E]] DEBUG 2020-03-01 14:44:00,375 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData (NodeID=63, Payload=[0x98, 0x40], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60) DEBUG 2020-03-01 14:44:00,375 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:00,375 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:00,375 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:44:00,375 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61)' request (callback ID=0x61). DEBUG 2020-03-01 14:44:00,375 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x1E, 0x00, 0x13, 0x3F, 0x17, 0x98, 0x81, 0x23, 0xD3, 0xFB, 0x98, 0x80, 0x07, 0xEA, 0x1B, 0x93, 0x0B, 0x0E, 0xFC, 0x55, 0x44, 0xD2, 0x8B, 0x67, 0x3B, 0x8E, 0x1C, 0xA2, 0x05, 0x61, 0x0C]. DEBUG 2020-03-01 14:44:00,378 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:44:00,378 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x1E, 0x00, 0x13, 0x3F, 0x17, 0x98, 0x81, 0x23, 0xD3, 0xFB, 0x98, 0x80, 0x07, 0xEA, 0x1B, 0x93, 0x0B, 0x0E, 0xFC, 0x55, 0x44, 0xD2, 0x8B, 0x67, 0x3B, 0x8E, 0x1C, 0xA2, 0x05, 0x61, 0x0C] transmission OK. DEBUG 2020-03-01 14:44:00,378 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:44:00,380 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:00,380 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:44:00,380 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:44:00,381 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x61). DEBUG 2020-03-01 14:44:00,381 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x61). DEBUG 2020-03-01 14:44:01,615 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:01,615 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x61, 0x00, 0x88] DEBUG 2020-03-01 14:44:01,615 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x61, 0x00, 0x88] has been received. DEBUG 2020-03-01 14:44:01,615 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x61, 0x00, 0x88] (callback ID=0x61) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions= [TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61)' successfully executed. DEBUG 2020-03-01 14:44:01,615 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData (NodeID=63, Payload=[0x86, 0x13, 0x5E], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61) DEBUG 2020-03-01 14:44:01,690 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:01,690 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] DEBUG 2020-03-01 14:44:01,690 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x08, 0x00, 0x04, 0x00, 0x3F, 0x02, 0x98, 0x40, 0x16] has been received. DEBUG 2020-03-01 14:44:01,690 (Z-Wave): RX_Session_Layer : Node '63' : [APP_CMD_HANDLER_FRAME : NodeID='63', Status= [RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SECURITY', Command='0x40', Parameters: [ - ]] DEBUG 2020-03-01 14:44:01,691 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:44:01,691 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[82, 51, 9E, 46, A8, 64, 43, 82]] DEBUG 2020-03-01 14:44:01,691 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:01,691 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:01,691 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_GET] received. DEBUG 2020-03-01 14:44:01,691 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:44:01,691 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[37, CF, 32, DC, 06, CE, 5A, 13]] DEBUG 2020-03-01 14:44:01,691 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62)' request (callback ID=0x62). DEBUG 2020-03-01 14:44:01,691 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:01,691 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82, 0x05, 0x62, 0xB1]. DEBUG 2020-03-01 14:44:01,693 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:44:01,693 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82, 0x05, 0x62, 0xB1] transmission OK. DEBUG 2020-03-01 14:44:01,694 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:44:01,696 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:01,696 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:44:01,696 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:44:01,697 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x62). DEBUG 2020-03-01 14:44:01,697 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x62). DEBUG 2020-03-01 14:44:02,927 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:02,927 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x62, 0x00, 0x8B] DEBUG 2020-03-01 14:44:02,927 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x62, 0x00, 0x8B] has been received. DEBUG 2020-03-01 14:44:02,927 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x62, 0x00, 0x8B] (callback ID=0x62) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x82, 0x51, 0x9E, 0x46, 0xA8, 0x64, 0x43, 0x82], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62)' successfully executed. DEBUG 2020-03-01 14:44:02,927 (Z-Wave): CCSecurity : Node '63' : [COMMAND_CLASS_SECURITY::SECURITY_NONCE_REPORT, Nonce=[82, 51, 9E, 46, A8, 64, 43, 82]] succeeded DEBUG 2020-03-01 14:44:02,927 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData (NodeID=63, Payload=[0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-01 14:44:02,927 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-01 14:44:02,927 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=63, Payload= [0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63)' request (callback ID=0x63). DEBUG 2020-03-01 14:44:02,927 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13, 0x05, 0x63, 0x21]. DEBUG 2020-03-01 14:44:02,930 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-01 14:44:02,930 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x11, 0x00, 0x13, 0x3F, 0x0A, 0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13, 0x05, 0x63, 0x21] transmission OK. DEBUG 2020-03-01 14:44:02,930 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=63, Payload=[0x98, 0x80, 0x37, 0xCF, 0x32, 0xDC, 0x06, 0xCE, 0x5A, 0x13], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-01 14:44:02,933 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-01 14:44:02,933 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-01 14:44:02,933 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-01 14:44:02,934 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x63). DEBUG 2020-03-01 14:44:02,934 (Z-Wave): RX_Session_Layer : Waiting 64 994 [ms] for 'ZW_SendData' callback frame (callback ID=0x63).