DEBUG 2020-03-16 17:10:41,517 (Z-Wave): Command_Builder : Z-Wave command: command = SECURE_INCLUSION_MODE_ON DEBUG 2020-03-16 17:10:41,517 (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-16 17:10:41,517 (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-16 17:10:41,517 (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-16 17:10:41,517 (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=0x5C)' call. DEBUG 2020-03-16 17:10:41,517 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0xC1, 0x5C, 0x2D]. DEBUG 2020-03-16 17:10:41,517 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:10:41,517 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0xC1, 0x5C, 0x2D] transmission OK. DEBUG 2020-03-16 17:10:41,517 (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=0x5C)' has been successfully sent --> waiting '10 000' [ms] for 'ADD_NODE_STATUS_LEARN_READY' callback. DEBUG 2020-03-16 17:10:41,517 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:10:41,517 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x5C, 0x01, 0x00, 0x00, 0xEF] DEBUG 2020-03-16 17:10:41,517 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x01, 0x00, 0x00, 0xEF] has been received. DEBUG 2020-03-16 17:10:41,533 (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=0x5C)' has received 'ADD_NODE_STATUS_LEARN_READY' callback --> waiting '60 000' [ms] for 'ADD_NODE_STATUS_NODE_FOUND' callback. DEBUG 2020-03-16 17:10:50,751 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:10:50,751 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x5C, 0x02, 0x00, 0x00, 0xEC] DEBUG 2020-03-16 17:10:50,751 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x02, 0x00, 0x00, 0xEC] has been received. DEBUG 2020-03-16 17:10:50,751 (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=0x5C)' has received 'ADD_NODE_STATUS_NODE_FOUND' callback frame [Status: ADD_NODE_STATUS_NODE_FOUND, Callback ID: 0x5C, 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-16 17:10:50,990 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:10:50,990 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x4A, 0x5C, 0x03, 0x45, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0x05] DEBUG 2020-03-16 17:10:50,990 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x4A, 0x5C, 0x03, 0x45, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0x05] has been received. DEBUG 2020-03-16 17:10:50,990 (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=0x5C)' has received 'ADD_NODE_STATUS_ADDING_SLAVE' callback frame [Status: ADD_NODE_STATUS_ADDING_SLAVE, Callback ID: 0x5C, Node ID: 69, 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-16 17:10:55,895 (Z-Wave): Node_Initializer : Node '9' : [ZW_RequestNodeInfo, (Retry='2')] DEBUG 2020-03-16 17:10:55,895 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=9)'. DEBUG 2020-03-16 17:11:21,192 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,192 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x5C, 0x05, 0x45, 0x00, 0xAE] DEBUG 2020-03-16 17:11:21,192 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x05, 0x45, 0x00, 0xAE] has been received. DEBUG 2020-03-16 17:11:21,192 (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=0x5C)' has received 'ADD_NODE_STATUS_PROTOCOL_DONE' callback frame [Status: ADD_NODE_STATUS_PROTOCOL_DONE, Callback ID: 0x5C, Node ID: 69, Device Classes: [Basic: 0x00, Generic: 0x00, Specific: 0x00], Command Classes: [Supported: [ -- ], Controlled: [ -- ]]]. DEBUG 2020-03-16 17:11:21,192 (Z-Wave): TX_Session_Layer : Sending Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' call. DEBUG 2020-03-16 17:11:21,207 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x5C, 0xE9]. DEBUG 2020-03-16 17:11:21,207 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:21,207 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x5C, 0xE9] transmission OK. DEBUG 2020-03-16 17:11:21,207 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' has been successfully sent --> waiting '500' [ms] for 'ADD_NODE_STATUS_DONE' callback. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] has been received. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' successfully executed. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): TX_Session_Layer : Sending Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' call. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x5C, 0xE9]. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x05, 0x00, 0x4A, 0x05, 0x5C, 0xE9] transmission OK. DEBUG 2020-03-16 17:11:21,248 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' has been successfully sent --> waiting '500' [ms] for 'ADD_NODE_STATUS_DONE' callback. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] has been received. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x07, 0x00, 0x4A, 0x5C, 0x06, 0x45, 0x00, 0xAD] -> Z-Wave function 'ZW_AddNodeToNetwork(Mode=ADD_NODE_STOP, callback ID: 0x5C)' successfully executed. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=9)'. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_RequestNodeInfo(NodeID=9)'... DEBUG 2020-03-16 17:11:21,303 (Z-Wave): ZW_RequestNodeInfo : Sending Z-Wave function 'ZW_RequestNodeInfo(NodeID=9)' call. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x60, 0x09, 0x92]. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): Inclusion_Manager : Node '69' : [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-16 17:11:21,303 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x60, 0x09, 0x92] transmission OK. DEBUG 2020-03-16 17:11:21,303 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=9)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:21,333 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,333 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x60, 0x01, 0x9B] DEBUG 2020-03-16 17:11:21,333 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x60, 0x01, 0x9B] has been received. DEBUG 2020-03-16 17:11:21,333 (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=9)'. DEBUG 2020-03-16 17:11:21,452 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,452 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x20, 0x00, 0x49, 0x84, 0x09, 0x1A, 0x04, 0x11, 0x01, 0x5E, 0x20, 0x86, 0x72, 0x26, 0x5A, 0x59, 0x85, 0x73, 0x98, 0x7A, 0x56, 0x70, 0x31, 0x32, 0x8E, 0x60, 0x75, 0x71, 0x27, 0x22, 0xEF, 0x2B, 0xA0] DEBUG 2020-03-16 17:11:21,468 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x20, 0x00, 0x49, 0x84, 0x09, 0x1A, 0x04, 0x11, 0x01, 0x5E, 0x20, 0x86, 0x72, 0x26, 0x5A, 0x59, 0x85, 0x73, 0x98, 0x7A, 0x56, 0x70, 0x31, 0x32, 0x8E, 0x60, 0x75, 0x71, 0x27, 0x22, 0xEF, 0x2B, 0xA0] has been received. DEBUG 2020-03-16 17:11:21,468 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x20, 0x00, 0x49, 0x84, 0x09, 0x1A, 0x04, 0x11, 0x01, 0x5E, 0x20, 0x86, 0x72, 0x26, 0x5A, 0x59, 0x85, 0x73, 0x98, 0x7A, 0x56, 0x70, 0x31, 0x32, 0x8E, 0x60, 0x75, 0x71, 0x27, 0x22, 0xEF, 0x2B, 0xA0] -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=9)' successfully executed. DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Node_Initializer : Node '9' : [ZW_RequestNodeInfo, (Retry='2')] succeeded : [[Status: UPDATE_STATE_NODE_INFO_RECEIVED, Node ID: 9, Device Classes: [Basic: BASIC_TYPE_ROUTING_SLAVE, Generic: GENERIC_TYPE_SWITCH_MULTILEVEL, Specific: SPECIFIC_TYPE_POWER_SWITCH_MULTILEVEL], Command Classes: [Supported: [COMMAND_CLASS_ZWAVEPLUS_INFO, COMMAND_CLASS_BASIC, COMMAND_CLASS_VERSION, COMMAND_CLASS_MANUFACTURER_SPECIFIC, COMMAND_CLASS_SWITCH_MULTILEVEL, COMMAND_CLASS_DEVICE_RESET_LOCALLY, COMMAND_CLASS_ASSOCIATION_GRP_INFO, COMMAND_CLASS_ASSOCIATION, COMMAND_CLASS_POWERLEVEL, COMMAND_CLASS_SECURITY, COMMAND_CLASS_FIRMWARE_UPDATE_MD, COMMAND_CLASS_CRC_16_ENCAP, COMMAND_CLASS_CONFIGURATION, COMMAND_CLASS_SENSOR_MULTILEVEL, COMMAND_CLASS_METER, COMMAND_CLASS_MULTI_INSTANCE_ASSOCIATION, COMMAND_CLASS_MULTI_INSTANCE, COMMAND_CLASS_PROTECTION, COMMAND_CLASS_ALARM, COMMAND_CLASS_SWITCH_ALL, COMMAND_CLASS_APPLICATION_STATUS], Controlled: [COMMAND_CLASS_SCENE_ACTIVATION]]]]. DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Node_Initializer : Node '9' : ====== Update dynamic data of command classes... DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC] DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:21,468 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:21,468 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:21,468 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D)' request (callback ID=0x5D). DEBUG 2020-03-16 17:11:21,468 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x5D, 0x94]. DEBUG 2020-03-16 17:11:21,481 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:21,481 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x5D, 0x94] transmission OK. DEBUG 2020-03-16 17:11:21,481 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 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-16 17:11:21,483 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:21,483 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:21,484 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:21,484 (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-16 17:11:21,484 (Z-Wave): RX_Session_Layer : Waiting 64 991 [ms] for 'ZW_SendData' callback frame (callback ID=0x5D). DEBUG 2020-03-16 17:11:22,066 (Z-Wave): Secure_Inclusion_Manager : Node '69' : [COMMAND_CLASS_SECURITY::SECURITY_SCHEME_GET] DEBUG 2020-03-16 17:11:22,082 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:22,193 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:22,193 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x20, 0x00, 0x49, 0x84, 0x09, 0x1A, 0x04, 0x11, 0x01, 0x5E, 0x20, 0x86, 0x72, 0x26, 0x5A, 0x59, 0x85, 0x73, 0x98, 0x7A, 0x56, 0x70, 0x31, 0x32, 0x8E, 0x60, 0x75, 0x71, 0x27, 0x22, 0xEF, 0x2B, 0xA0] DEBUG 2020-03-16 17:11:22,193 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x20, 0x00, 0x49, 0x84, 0x09, 0x1A, 0x04, 0x11, 0x01, 0x5E, 0x20, 0x86, 0x72, 0x26, 0x5A, 0x59, 0x85, 0x73, 0x98, 0x7A, 0x56, 0x70, 0x31, 0x32, 0x8E, 0x60, 0x75, 0x71, 0x27, 0x22, 0xEF, 0x2B, 0xA0] has been received. DEBUG 2020-03-16 17:11:22,193 (Z-Wave): RX_Session_Layer : 'Application Controller Update' frame [Status: UPDATE_STATE_NODE_INFO_RECEIVED, Node ID: 9, Device Classes: [Basic: BASIC_TYPE_ROUTING_SLAVE, Generic: GENERIC_TYPE_SWITCH_MULTILEVEL, Specific: SPECIFIC_TYPE_POWER_SWITCH_MULTILEVEL], Command Classes: [Supported: [COMMAND_CLASS_ZWAVEPLUS_INFO, COMMAND_CLASS_BASIC, COMMAND_CLASS_VERSION, COMMAND_CLASS_MANUFACTURER_SPECIFIC, COMMAND_CLASS_SWITCH_MULTILEVEL, COMMAND_CLASS_DEVICE_RESET_LOCALLY, COMMAND_CLASS_ASSOCIATION_GRP_INFO, COMMAND_CLASS_ASSOCIATION, COMMAND_CLASS_POWERLEVEL, COMMAND_CLASS_SECURITY, COMMAND_CLASS_FIRMWARE_UPDATE_MD, COMMAND_CLASS_CRC_16_ENCAP, COMMAND_CLASS_CONFIGURATION, COMMAND_CLASS_SENSOR_MULTILEVEL, COMMAND_CLASS_METER, COMMAND_CLASS_MULTI_INSTANCE_ASSOCIATION, COMMAND_CLASS_MULTI_INSTANCE, COMMAND_CLASS_PROTECTION, COMMAND_CLASS_ALARM, COMMAND_CLASS_SWITCH_ALL, COMMAND_CLASS_APPLICATION_STATUS], Controlled: [COMMAND_CLASS_SCENE_ACTIVATION]]] has been received. DEBUG 2020-03-16 17:11:22,635 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:22,635 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5D, 0x01, 0xB5] DEBUG 2020-03-16 17:11:22,635 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5D, 0x01, 0xB5] has been received. DEBUG 2020-03-16 17:11:22,635 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x5D, 0x01, 0xB5] (callback ID=0x5D) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5D)' ERROR 2020-03-16 17:11:22,635 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:22,635 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_V2] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION_V2] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E)' request (callback ID=0x5E). DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='1')] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x45, 0x03, 0x98, 0x04, 0x00, 0x05, 0x5E, 0x67]. DEBUG 2020-03-16 17:11:22,650 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:22,650 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:22,650 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x45, 0x03, 0x98, 0x04, 0x00, 0x05, 0x5E, 0x67] transmission OK. DEBUG 2020-03-16 17:11:22,650 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], 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-16 17:11:22,671 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:22,671 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:22,671 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:22,671 (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-16 17:11:22,671 (Z-Wave): RX_Session_Layer : Waiting 64 975 [ms] for 'ZW_SendData' callback frame (callback ID=0x5E). DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5E, 0x00, 0xB7] DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5E, 0x00, 0xB7] has been received. DEBUG 2020-03-16 17:11:24,163 (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=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E)' successfully executed. DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E) DEBUG 2020-03-16 17:11:24,163 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:24,163 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:24,163 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F)' request (callback ID=0x5F). DEBUG 2020-03-16 17:11:24,163 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x26, 0x02, 0x05, 0x5F, 0x90]. DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x26, 0x02, 0x05, 0x5F, 0x90] transmission OK. DEBUG 2020-03-16 17:11:24,163 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], 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-16 17:11:24,192 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,192 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:24,192 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:24,192 (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-16 17:11:24,192 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x5F). DEBUG 2020-03-16 17:11:24,232 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,232 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x5F, 0x00, 0xB6] DEBUG 2020-03-16 17:11:24,232 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x5F, 0x00, 0xB6] has been received. DEBUG 2020-03-16 17:11:24,232 (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=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F)' successfully executed. DEBUG 2020-03-16 17:11:24,232 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='2') : ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F) DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x26, 0x03, 0x00, 0xDD] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x26, 0x03, 0x00, 0xDD] has been received. DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_REPORT (Retry='1'), Value='0'] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5F) DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_CONFIGURATION] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:24,370 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:24,370 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:24,370 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60)' request (callback ID=0x60). DEBUG 2020-03-16 17:11:24,370 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x31, 0x04, 0x05, 0x60, 0xBE]. DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x31, 0x04, 0x05, 0x60, 0xBE] transmission OK. DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], 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-16 17:11:24,370 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:24,370 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:24,370 (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-16 17:11:24,370 (Z-Wave): RX_Session_Layer : Waiting 64 997 [ms] for 'ZW_SendData' callback frame (callback ID=0x60). DEBUG 2020-03-16 17:11:24,453 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,453 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x60, 0x00, 0x89] DEBUG 2020-03-16 17:11:24,453 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x60, 0x00, 0x89] has been received. DEBUG 2020-03-16 17:11:24,453 (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=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60)' successfully executed. DEBUG 2020-03-16 17:11:24,453 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='2') : ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60) DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x09, 0x06, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0xEA] DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x09, 0x06, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0xEA] has been received. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='0', Type='POWER', Scale='POWER_SCALE_WATT']. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_ALARM_V2] DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x60) DEBUG 2020-03-16 17:11:24,524 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='1'), AlarmType='ALARM_TYPE_SYSTEM'] DEBUG 2020-03-16 17:11:24,524 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:24,524 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61)' request (callback ID=0x61). DEBUG 2020-03-16 17:11:24,524 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x09, 0x05, 0x61, 0xF2]. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x09, 0x05, 0x61, 0xF2] transmission OK. DEBUG 2020-03-16 17:11:24,524 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], 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-16 17:11:24,534 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:24,534 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:24,534 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:24,534 (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-16 17:11:24,534 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x61). DEBUG 2020-03-16 17:11:25,057 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,057 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] DEBUG 2020-03-16 17:11:25,057 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] has been received. DEBUG 2020-03-16 17:11:25,496 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01, 0x05, 0x00, 0x13, 0x61, 0x01, 0x89] DEBUG 2020-03-16 17:11:25,496 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x61, 0x01, 0x89] has been received. DEBUG 2020-03-16 17:11:25,496 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x61, 0x01, 0x89] (callback ID=0x61) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x61)' ERROR 2020-03-16 17:11:25,497 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:25,514 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='2'), AlarmType='ALARM_TYPE_SYSTEM'] DEBUG 2020-03-16 17:11:25,514 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:25,514 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:25,514 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:25,514 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62)' request (callback ID=0x62). DEBUG 2020-03-16 17:11:25,514 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x09, 0x05, 0x62, 0xF1]. DEBUG 2020-03-16 17:11:25,514 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:25,514 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x09, 0x05, 0x62, 0xF1] transmission OK. DEBUG 2020-03-16 17:11:25,514 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], 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-16 17:11:25,544 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,544 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:25,544 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:25,544 (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-16 17:11:25,545 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x62). DEBUG 2020-03-16 17:11:25,667 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,683 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x62, 0x00, 0x8B] DEBUG 2020-03-16 17:11:25,683 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x62, 0x00, 0x8B] has been received. DEBUG 2020-03-16 17:11:25,683 (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=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62)' successfully executed. DEBUG 2020-03-16 17:11:25,683 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='2') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62) DEBUG 2020-03-16 17:11:25,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] has been received. DEBUG 2020-03-16 17:11:25,751 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='2'), AlarmType='ALARM_TYPE_SYSTEM', AlarmTypeValue='0x09', AlarmEvent='EVENT_UNKNWON', AlarmEventValue='0xFE'] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='1'), AlarmType='ALARM_TYPE_POWER_MANAGEMENT'] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x62) DEBUG 2020-03-16 17:11:25,751 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:25,751 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:25,751 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:25,751 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63)' request (callback ID=0x63). DEBUG 2020-03-16 17:11:25,751 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x08, 0x05, 0x63, 0xF1]. DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x08, 0x05, 0x63, 0xF1] transmission OK. DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], 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-16 17:11:25,751 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:25,751 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:25,751 (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-16 17:11:25,751 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x63). DEBUG 2020-03-16 17:11:25,941 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:25,941 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x63, 0x00, 0x8A] DEBUG 2020-03-16 17:11:25,941 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x63, 0x00, 0x8A] has been received. DEBUG 2020-03-16 17:11:25,941 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x63, 0x00, 0x8A] (callback ID=0x63) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63)' successfully executed. DEBUG 2020-03-16 17:11:25,941 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='2') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63) DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x09, 0xFE, 0x00, 0x88] has been received. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='1'), AlarmType='ALARM_TYPE_SYSTEM', AlarmTypeValue='0x09', AlarmEvent='EVENT_UNKNWON', AlarmEventValue='0xFE'] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='1'), AlarmType='ALARM_TYPE_HEAT'] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x08], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x63) DEBUG 2020-03-16 17:11:26,066 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:26,066 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x64)' request (callback ID=0x64). DEBUG 2020-03-16 17:11:26,066 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x04, 0x05, 0x64, 0xFA]. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0B, 0x00, 0x13, 0x09, 0x04, 0x71, 0x04, 0x00, 0x04, 0x05, 0x64, 0xFA] transmission OK. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x64)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x64). DEBUG 2020-03-16 17:11:26,066 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x64). DEBUG 2020-03-16 17:11:26,439 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:26,439 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x64, 0x00, 0x8D] DEBUG 2020-03-16 17:11:26,439 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x64, 0x00, 0x8D] has been received. DEBUG 2020-03-16 17:11:26,439 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x64, 0x00, 0x8D] (callback ID=0x64) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x64)' successfully executed. DEBUG 2020-03-16 17:11:26,439 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='2') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x64) DEBUG 2020-03-16 17:11:26,551 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] has been received. DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='1'), AlarmType='ALARM_TYPE_HEAT', AlarmTypeValue='0x04', AlarmEvent='EVENT_UNKNWON', AlarmEventValue='0xFE'] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_METER_V3] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_METER::METER_GET (Retry='1'), Scale='ELECTRIC_METER_SCALE_W'] DEBUG 2020-03-16 17:11:26,551 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x64) DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:26,551 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:26,551 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:26,551 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x65)' request (callback ID=0x65). DEBUG 2020-03-16 17:11:26,551 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x09, 0x03, 0x32, 0x01, 0x10, 0x05, 0x65, 0xAF]. DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x09, 0x03, 0x32, 0x01, 0x10, 0x05, 0x65, 0xAF] transmission OK. DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x65)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x65). DEBUG 2020-03-16 17:11:26,566 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x65). DEBUG 2020-03-16 17:11:27,033 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:27,033 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] DEBUG 2020-03-16 17:11:27,033 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] has been received. DEBUG 2020-03-16 17:11:27,033 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_ALARM', Command='0x05', Parameters: [0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00]] DEBUG 2020-03-16 17:11:27,033 (Z-Wave): CCAlarm : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:27,033 (Z-Wave): CCAlarmV2 : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. DEBUG 2020-03-16 17:11:27,033 (Z-Wave): CCAlarm : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:27,033 (Z-Wave): CCAlarmV2 : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. DEBUG 2020-03-16 17:11:27,749 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:27,749 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x65, 0x01, 0x8D] DEBUG 2020-03-16 17:11:27,749 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x65, 0x01, 0x8D] has been received. DEBUG 2020-03-16 17:11:27,749 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x65, 0x01, 0x8D] (callback ID=0x65) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x65)' ERROR 2020-03-16 17:11:27,749 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_METER::METER_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:27,777 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_METER::METER_GET (Retry='2'), Scale='ELECTRIC_METER_SCALE_W'] DEBUG 2020-03-16 17:11:27,777 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:27,777 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:27,777 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:27,777 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x66)' request (callback ID=0x66). DEBUG 2020-03-16 17:11:27,777 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x09, 0x03, 0x32, 0x01, 0x10, 0x05, 0x66, 0xAC]. DEBUG 2020-03-16 17:11:27,777 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:27,777 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x09, 0x03, 0x32, 0x01, 0x10, 0x05, 0x66, 0xAC] transmission OK. DEBUG 2020-03-16 17:11:27,777 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x66)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:27,793 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:27,793 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:27,793 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:27,793 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x66). DEBUG 2020-03-16 17:11:27,793 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x66). DEBUG 2020-03-16 17:11:28,225 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:28,225 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] DEBUG 2020-03-16 17:11:28,225 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] has been received. DEBUG 2020-03-16 17:11:28,226 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_ALARM', Command='0x05', Parameters: [0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00]] DEBUG 2020-03-16 17:11:28,226 (Z-Wave): CCAlarm : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:28,226 (Z-Wave): CCAlarmV2 : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. DEBUG 2020-03-16 17:11:28,226 (Z-Wave): CCAlarm : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:28,226 (Z-Wave): CCAlarmV2 : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. ERROR 2020-03-16 17:11:29,179 (Z-Wave): Secure_Inclusion_Manager : Node '69' : [COMMAND_CLASS_SECURITY::SECURITY_SCHEME_GET] failed : 'COMMAND_CLASS_REPORT_TIMEOUT_ERROR'. ERROR 2020-03-16 17:11:29,195 (Z-Wave): Inclusion_Manager : Node '69' : The security enabled Z-Wave node has been added to the *NON SECURE* Z-Wave network because failed to set the network key. DEBUG 2020-03-16 17:11:29,202 (Z-Wave): Controller : Retrieving Z-Wave node info (ID=69)... DEBUG 2020-03-16 17:11:29,203 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'. DEBUG 2020-03-16 17:11:29,886 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:29,886 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x66, 0x01, 0x8E] DEBUG 2020-03-16 17:11:29,886 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x66, 0x01, 0x8E] has been received. DEBUG 2020-03-16 17:11:29,886 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x66, 0x01, 0x8E] (callback ID=0x66) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x66)' DEBUG 2020-03-16 17:11:29,886 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'. ERROR 2020-03-16 17:11:29,886 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_METER::METER_GET (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:29,886 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'... DEBUG 2020-03-16 17:11:29,886 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=69, Payload=[0x98, 0x04, 0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x5E) DEBUG 2020-03-16 17:11:29,886 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x41, 0x45, 0xFF]. DEBUG 2020-03-16 17:11:29,886 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_PROTECTION_V2] DEBUG 2020-03-16 17:11:29,897 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_PROTECTION1::PROTECTION_GET (Retry='1')] DEBUG 2020-03-16 17:11:29,897 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:29,898 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:29,898 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x45, 0xFF] transmission OK. DEBUG 2020-03-16 17:11:29,899 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=69)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:29,899 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] has been received. DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Session_Layer : Response received [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=69)' successfully executed. DEBUG 2020-03-16 17:11:29,900 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. INFO 2020-03-16 17:11:29,900 (Z-Wave): Controller : Z-Wave node info (ID=69) : [[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-16 17:11:29,900 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:29,900 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x67)' request (callback ID=0x67). DEBUG 2020-03-16 17:11:29,900 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x75, 0x02, 0x05, 0x67, 0xFB]. DEBUG 2020-03-16 17:11:29,900 (Z-Wave): Node_Initializer : Node '69' : [ZW_RequestNodeInfo, (Retry='1')] DEBUG 2020-03-16 17:11:29,900 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=69)'. DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x75, 0x02, 0x05, 0x67, 0xFB] transmission OK. DEBUG 2020-03-16 17:11:29,900 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x67)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:29,929 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:29,930 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:29,930 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:29,930 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x67). DEBUG 2020-03-16 17:11:29,930 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x67). DEBUG 2020-03-16 17:11:30,431 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:30,431 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] DEBUG 2020-03-16 17:11:30,431 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x09, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00, 0x85] has been received. DEBUG 2020-03-16 17:11:30,431 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_ALARM', Command='0x05', Parameters: [0x00, 0x00, 0x00, 0xFF, 0x04, 0xFE, 0x00]] DEBUG 2020-03-16 17:11:30,431 (Z-Wave): CCAlarm : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:30,431 (Z-Wave): CCAlarmV2 : Node '9:0' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. DEBUG 2020-03-16 17:11:30,431 (Z-Wave): CCAlarm : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00']]. DEBUG 2020-03-16 17:11:30,431 (Z-Wave): CCAlarmV2 : Node '9:1' : [COMMAND_CLASS_ALARM::ALARM_REPORT, AlarmTypeV1=[Dec:'0' Hex:'0x00'], AlarmLevelV1=[Dec:'0' Hex:'0x00'], AlarmStatus='UNSOLICITED_ALARMS_ENABLED', AlarmType=['ALARM_TYPE_HEAT', Dec:'4', Hex:'0x04'], EventType=['EVENT_UNKNWON', Dec:'254', Hex: '0xFE']]. DEBUG 2020-03-16 17:11:31,476 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:31,476 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x67, 0x01, 0x8F] DEBUG 2020-03-16 17:11:31,476 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x67, 0x01, 0x8F] has been received. DEBUG 2020-03-16 17:11:31,476 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x67, 0x01, 0x8F] (callback ID=0x67) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x67)' ERROR 2020-03-16 17:11:31,476 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_PROTECTION_V2::PROTECTION_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:31,476 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=69)'. DEBUG 2020-03-16 17:11:31,492 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_RequestNodeInfo(NodeID=69)'... DEBUG 2020-03-16 17:11:31,492 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_PROTECTION2::PROTECTION_GET (Retry='2')] DEBUG 2020-03-16 17:11:31,492 (Z-Wave): ZW_RequestNodeInfo : Sending Z-Wave function 'ZW_RequestNodeInfo(NodeID=69)' call. DEBUG 2020-03-16 17:11:31,492 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x60, 0x45, 0xDE]. DEBUG 2020-03-16 17:11:31,492 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:31,492 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:31,492 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x60, 0x45, 0xDE] transmission OK. DEBUG 2020-03-16 17:11:31,492 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=69)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:31,516 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:31,516 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x60, 0x01, 0x9B] DEBUG 2020-03-16 17:11:31,516 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x60, 0x01, 0x9B] has been received. DEBUG 2020-03-16 17:11:31,516 (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=69)'. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x49, 0x84, 0x45, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xC2] DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x49, 0x84, 0x45, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xC2] has been received. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x0F, 0x00, 0x49, 0x84, 0x45, 0x09, 0x04, 0x40, 0x03, 0x5E, 0x98, 0x9F, 0x55, 0x72, 0x8A, 0xC2] -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=69)' successfully executed. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:33,354 (Z-Wave): Node_Initializer : Node '69' : [ZW_RequestNodeInfo, (Retry='1')] succeeded : [[Status: UPDATE_STATE_NODE_INFO_RECEIVED, Node ID: 69, 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-16 17:11:33,354 (Z-Wave): Node_Initializer : Node '69' : [ZW_GetNodeProtocolInfo]... DEBUG 2020-03-16 17:11:33,354 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x68)' request (callback ID=0x68). DEBUG 2020-03-16 17:11:33,354 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x75, 0x02, 0x05, 0x68, 0xF4]. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x75, 0x02, 0x05, 0x68, 0xF4] transmission OK. DEBUG 2020-03-16 17:11:33,354 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x68)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:33,382 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:33,383 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:33,383 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:33,383 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x68). DEBUG 2020-03-16 17:11:33,383 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x68). DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x68, 0x00, 0x81] DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x68, 0x00, 0x81] has been received. DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x68, 0x00, 0x81] (callback ID=0x68) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x68)' successfully executed. DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x68) DEBUG 2020-03-16 17:11:34,114 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'. DEBUG 2020-03-16 17:11:34,114 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=69)'... DEBUG 2020-03-16 17:11:34,114 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x41, 0x45, 0xFF]. DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x45, 0xFF] transmission OK. DEBUG 2020-03-16 17:11:34,114 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=69)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:34,123 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:34,124 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] DEBUG 2020-03-16 17:11:34,124 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] has been received. DEBUG 2020-03-16 17:11:34,124 (Z-Wave): RX_Session_Layer : Response received [0x01, 0x09, 0x01, 0x41, 0x53, 0xDC, 0x01, 0x04, 0x40, 0x03, 0x7F] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=69)' successfully executed. DEBUG 2020-03-16 17:11:34,124 (Z-Wave): Node_Initializer : Node '69' : [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-16 17:11:34,124 (Z-Wave): Node_Initializer : Node '69' : [COMMAND_CLASS_MANUFACTURER_SPECIFIC::MANUFACTURER_SPECIFIC_GET (Retry='1')]... DEBUG 2020-03-16 17:11:34,126 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:34,126 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x69)' request (callback ID=0x69). DEBUG 2020-03-16 17:11:34,126 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x72, 0x04, 0x05, 0x69, 0xB8]. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x72, 0x04, 0x05, 0x69, 0xB8] transmission OK. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x69)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x69). DEBUG 2020-03-16 17:11:34,126 (Z-Wave): RX_Session_Layer : Waiting 64 995 [ms] for 'ZW_SendData' callback frame (callback ID=0x69). DEBUG 2020-03-16 17:11:34,348 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:34,348 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0A, 0x00, 0x04, 0x00, 0x09, 0x04, 0x75, 0x03, 0x00, 0x00, 0x8A] DEBUG 2020-03-16 17:11:34,348 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0A, 0x00, 0x04, 0x00, 0x09, 0x04, 0x75, 0x03, 0x00, 0x00, 0x8A] has been received. DEBUG 2020-03-16 17:11:34,348 (Z-Wave): Node_Initializer : Node '9:0' : [COMMAND_CLASS_PROTECTION_V2::PROTECTION_REPORT (Retry='2'), Local='LOCAL_UNPROTECTED', RF='RF_UNPROTECTED'] DEBUG 2020-03-16 17:11:34,348 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3] DEBUG 2020-03-16 17:11:34,348 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=9, Payload=[0x75, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x68) DEBUG 2020-03-16 17:11:34,348 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='1')] DEBUG 2020-03-16 17:11:34,348 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@15c289f1'. DEBUG 2020-03-16 17:11:37,863 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:37,863 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0A, 0x00, 0x04, 0x00, 0x09, 0x04, 0x75, 0x03, 0x00, 0x00, 0x8A] DEBUG 2020-03-16 17:11:37,863 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0A, 0x00, 0x04, 0x00, 0x09, 0x04, 0x75, 0x03, 0x00, 0x00, 0x8A] has been received. DEBUG 2020-03-16 17:11:37,863 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_PROTECTION', Command='0x03', Parameters: [0x00, 0x00]] DEBUG 2020-03-16 17:11:37,863 (Z-Wave): CCProtectionV2 : Node '9:0' : [COMMAND_CLASS_PROTECTION_V2::PROTECTION_REPORT, Local='LOCAL_UNPROTECTED']. DEBUG 2020-03-16 17:11:37,864 (Z-Wave): CCProtectionV2 : Node '9:0' : [COMMAND_CLASS_PROTECTION_V2::PROTECTION_REPORT, RF='RF_UNPROTECTED']. DEBUG 2020-03-16 17:11:41,597 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:41,597 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] DEBUG 2020-03-16 17:11:41,597 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] has been received. DEBUG 2020-03-16 17:11:43,993 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:43,993 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x69, 0x00, 0x80] DEBUG 2020-03-16 17:11:43,993 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x69, 0x00, 0x80] has been received. DEBUG 2020-03-16 17:11:43,994 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x69, 0x00, 0x80] (callback ID=0x69) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x72, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x69)' successfully executed. DEBUG 2020-03-16 17:11:43,995 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@15c289f1'. DEBUG 2020-03-16 17:11:43,995 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@15c289f1'... DEBUG 2020-03-16 17:11:43,995 (Z-Wave): Node_Initializer : Node '69' : [COMMAND_CLASS_MANUFACTURER_SPECIFIC::MANUFACTURER_SPECIFIC_REPORT (Retry='1'), Manufacturer ID='0x010E', Manufacturer='Poly-control', ProductTypeID='0x0009', ProductID='0x0001']. DEBUG 2020-03-16 17:11:43,995 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6A)' request (callback ID=0x6A). DEBUG 2020-03-16 17:11:43,995 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x26, 0x02, 0x05, 0x6A, 0xCA]. DEBUG 2020-03-16 17:11:43,995 (Z-Wave): Node_Initializer : Node '69' : Configure parameters... DEBUG 2020-03-16 17:11:43,995 (Z-Wave): Node_Initializer : Node '69' : ====== Init static data of command classes... DEBUG 2020-03-16 17:11:43,996 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:43,996 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x26, 0x02, 0x05, 0x6A, 0xCA] transmission OK. DEBUG 2020-03-16 17:11:43,996 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6A)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:43,996 (Z-Wave): Node_Initializer : Node '69' : ------ Update static data of : [COMMAND_CLASS_ZWAVEPLUS_INFO] DEBUG 2020-03-16 17:11:43,996 (Z-Wave): Node_Initializer : Node '69:0' : [COMMAND_CLASS_ZWAVEPLUS_INFO::ZWAVEPLUS_INFO_GET (Retry='1')] DEBUG 2020-03-16 17:11:43,996 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:44,011 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:44,011 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:44,011 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:44,011 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6A). DEBUG 2020-03-16 17:11:44,011 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x6A). DEBUG 2020-03-16 17:11:44,161 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:44,161 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] DEBUG 2020-03-16 17:11:44,161 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] has been received. DEBUG 2020-03-16 17:11:44,161 (Z-Wave): RX_Session_Layer : Node '69' : [APP_CMD_HANDLER_FRAME : NodeID='69', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MANUFACTURER_SPECIFIC', Command='0x05', Parameters: [0x01, 0x0E, 0x00, 0x09, 0x00, 0x01]] DEBUG 2020-03-16 17:11:44,987 (Z-Wave): Node_Initializer : Node '57' : [ZW_RequestNodeInfo, (Retry='2')] DEBUG 2020-03-16 17:11:44,987 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=2) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=57)'. DEBUG 2020-03-16 17:11:45,184 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:45,184 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6A, 0x01, 0x82] DEBUG 2020-03-16 17:11:45,184 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6A, 0x01, 0x82] has been received. DEBUG 2020-03-16 17:11:45,184 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6A, 0x01, 0x82] (callback ID=0x6A) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6A)' ERROR 2020-03-16 17:11:45,184 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:11:45,184 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='2')] DEBUG 2020-03-16 17:11:45,200 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:11:45,200 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=2) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@3379fc0a'. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6B)' request (callback ID=0x6B). DEBUG 2020-03-16 17:11:45,200 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x5E, 0x01, 0x05, 0x6B, 0x93]. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x5E, 0x01, 0x05, 0x6B, 0x93] transmission OK. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6B)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6B). DEBUG 2020-03-16 17:11:45,200 (Z-Wave): RX_Session_Layer : Waiting 64 996 [ms] for 'ZW_SendData' callback frame (callback ID=0x6B). DEBUG 2020-03-16 17:11:45,869 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:45,869 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] DEBUG 2020-03-16 17:11:45,869 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] has been received. DEBUG 2020-03-16 17:11:45,869 (Z-Wave): RX_Session_Layer : Node '69' : [APP_CMD_HANDLER_FRAME : NodeID='69', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MANUFACTURER_SPECIFIC', Command='0x05', Parameters: [0x01, 0x0E, 0x00, 0x09, 0x00, 0x01]] DEBUG 2020-03-16 17:11:46,787 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:46,787 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] DEBUG 2020-03-16 17:11:46,787 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0E, 0x00, 0x04, 0x00, 0x45, 0x08, 0x72, 0x05, 0x01, 0x0E, 0x00, 0x09, 0x00, 0x01, 0xC8] has been received. DEBUG 2020-03-16 17:11:46,787 (Z-Wave): RX_Session_Layer : Node '69' : [APP_CMD_HANDLER_FRAME : NodeID='69', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MANUFACTURER_SPECIFIC', Command='0x05', Parameters: [0x01, 0x0E, 0x00, 0x09, 0x00, 0x01]] DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6B, 0x00, 0x82] DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6B, 0x00, 0x82] has been received. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6B, 0x00, 0x82] (callback ID=0x6B) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6B)' successfully executed. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6B) DEBUG 2020-03-16 17:11:48,324 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_RequestNodeInfo(NodeID=57)'. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_RequestNodeInfo(NodeID=57)'... DEBUG 2020-03-16 17:11:48,324 (Z-Wave): ZW_RequestNodeInfo : Sending Z-Wave function 'ZW_RequestNodeInfo(NodeID=57)' call. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x04, 0x00, 0x60, 0x39, 0xA2]. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x04, 0x00, 0x60, 0x39, 0xA2] transmission OK. DEBUG 2020-03-16 17:11:48,324 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=57)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:11:50,216 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:50,216 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x60, 0x01, 0x9B] DEBUG 2020-03-16 17:11:50,216 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x60, 0x01, 0x9B] has been received. DEBUG 2020-03-16 17:11:50,216 (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=57)'. DEBUG 2020-03-16 17:11:50,308 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:11:50,308 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0F, 0x00, 0x04, 0x00, 0x45, 0x09, 0x5E, 0x02, 0x01, 0x07, 0x00, 0x03, 0x00, 0x03, 0x00, 0xE2] DEBUG 2020-03-16 17:11:50,309 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0F, 0x00, 0x04, 0x00, 0x45, 0x09, 0x5E, 0x02, 0x01, 0x07, 0x00, 0x03, 0x00, 0x03, 0x00, 0xE2] has been received. DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69:0' : [COMMAND_CLASS_ZWAVEPLUS_INFO::ZWAVEPLUS_INFO_REPORT (Retry='1'), Version : '1', RoleType : 'ZWAVEPLUS_ROLE_TYPE_SLAVE_SLEEPING_LISTENING', NodeType : 'ZWAVEPLUS_NODE_TYPE_NODE'] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ------ Update static data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ------ Update static data of : [COMMAND_CLASS_BASIC] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : Configure associations... DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : Configure wake up notification ID... DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ====== Update dynamic data of command classes... DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ------ Update dynamic data of : [COMMAND_CLASS_ZWAVEPLUS_INFO] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Node_Initializer : Node '69:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] DEBUG 2020-03-16 17:11:50,311 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=2) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:11:50,311 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=69, Payload=[0x5E, 0x01], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6B) DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Building Z-Wave command DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: command = WATER_LEAK_ALARM DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: nodeId = '27', endPoint = '0' DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: paramValue = null DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command created successfully DEBUG 2020-03-16 17:12:00,004 (Z-Wave): CCAlarmSensor : Node '27:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_GET, SensorType='WATER_LEAK_ALARM'] DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=3) Z-Wave function call 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Building Z-Wave command DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: nodeId = '31', endPoint = '0' DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: command = WATER_LEAK_ALARM DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command: paramValue = null DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Command_Builder : Z-Wave command created successfully DEBUG 2020-03-16 17:12:00,004 (Z-Wave): CCAlarmSensor : Node '31:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_GET, SensorType='WATER_LEAK_ALARM'] DEBUG 2020-03-16 17:12:00,004 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=4) Z-Wave function call 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06, 0x00, 0x49, 0x81, 0x00, 0x00, 0x31] DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x06, 0x00, 0x49, 0x81, 0x00, 0x00, 0x31] has been received. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Session_Layer : Callback received [0x01, 0x06, 0x00, 0x49, 0x81, 0x00, 0x00, 0x31] -> Z-Wave function 'ZW_RequestNodeInfo(NodeID=57)' successfully executed. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): Node_Initializer : Node '57' : [ZW_RequestNodeInfo, (Retry='2')] failed : 'REQUEST_NODE_INFO_NODE_ACK_ERROR'. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=3) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@3379fc0a'. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@3379fc0a'... DEBUG 2020-03-16 17:12:02,212 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6C)' request (callback ID=0x6C). DEBUG 2020-03-16 17:12:02,212 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x26, 0x02, 0x05, 0x6C, 0xCC]. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x26, 0x02, 0x05, 0x6C, 0xCC] transmission OK. DEBUG 2020-03-16 17:12:02,212 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6C)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:02,241 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:02,241 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:02,241 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:02,241 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6C). DEBUG 2020-03-16 17:12:02,241 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x6C). DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6C, 0x01, 0x84] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6C, 0x01, 0x84] has been received. DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6C, 0x01, 0x84] (callback ID=0x6C) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6C)' ERROR 2020-03-16 17:12:03,228 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=2) Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION_V2] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:12:03,228 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=3) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6de0935b'. DEBUG 2020-03-16 17:12:03,228 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6D)' request (callback ID=0x6D). DEBUG 2020-03-16 17:12:03,228 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x20, 0x02, 0x05, 0x6D, 0xE8]. DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x45, 0x02, 0x20, 0x02, 0x05, 0x6D, 0xE8] transmission OK. DEBUG 2020-03-16 17:12:03,228 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6D)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:03,259 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:03,259 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:03,259 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:03,259 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6D). DEBUG 2020-03-16 17:12:03,259 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x6D). DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6D, 0x00, 0x84] DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6D, 0x00, 0x84] has been received. DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6D, 0x00, 0x84] (callback ID=0x6D) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6D)' successfully executed. DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Session_Layer : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6D) DEBUG 2020-03-16 17:12:04,448 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=2) Z-Wave function call 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:12:04,448 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:12:04,448 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6E)' request (callback ID=0x6E). DEBUG 2020-03-16 17:12:04,448 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x1B, 0x03, 0x9C, 0x01, 0x05, 0x05, 0x6E, 0x0D]. DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x1B, 0x03, 0x9C, 0x01, 0x05, 0x05, 0x6E, 0x0D] transmission OK. DEBUG 2020-03-16 17:12:04,448 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6E)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:04,467 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,467 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:04,467 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:04,467 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6E). DEBUG 2020-03-16 17:12:04,467 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x6E). DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6E, 0x00, 0x87] DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6E, 0x00, 0x87] has been received. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6E, 0x00, 0x87] (callback ID=0x6E) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=27, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6E)' successfully executed. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): CCAlarmSensor : Node '27:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_GET, SensorType='WATER_LEAK_ALARM'] succeeded. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=1) Z-Wave function call 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'... DEBUG 2020-03-16 17:12:04,526 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6F)' request (callback ID=0x6F). DEBUG 2020-03-16 17:12:04,526 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0A, 0x00, 0x13, 0x1F, 0x03, 0x9C, 0x01, 0x05, 0x05, 0x6F, 0x08]. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0A, 0x00, 0x13, 0x1F, 0x03, 0x9C, 0x01, 0x05, 0x05, 0x6F, 0x08] transmission OK. DEBUG 2020-03-16 17:12:04,526 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6F)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:04,543 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,543 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:04,543 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:04,543 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x6F). DEBUG 2020-03-16 17:12:04,543 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x6F). DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x6F, 0x00, 0x86] DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x6F, 0x00, 0x86] has been received. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x6F, 0x00, 0x86] (callback ID=0x6F) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=31, Payload=[0x9C, 0x01, 0x05], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6F)' successfully executed. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): CCAlarmSensor : Node '31:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_GET, SensorType='WATER_LEAK_ALARM'] succeeded. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6de0935b'. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6de0935b'... DEBUG 2020-03-16 17:12:04,600 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x70)' request (callback ID=0x70). DEBUG 2020-03-16 17:12:04,600 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x20, 0x02, 0x05, 0x70, 0xD6]. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x20, 0x02, 0x05, 0x70, 0xD6] transmission OK. DEBUG 2020-03-16 17:12:04,600 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x70)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:04,632 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,632 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:04,632 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:04,632 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x70). DEBUG 2020-03-16 17:12:04,632 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x70). DEBUG 2020-03-16 17:12:04,659 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:04,659 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x1B, 0x07, 0x9C, 0x02, 0x1B, 0x05, 0x00, 0x00, 0x00, 0x6A] DEBUG 2020-03-16 17:12:04,659 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x1B, 0x07, 0x9C, 0x02, 0x1B, 0x05, 0x00, 0x00, 0x00, 0x6A] has been received. DEBUG 2020-03-16 17:12:04,659 (Z-Wave): RX_Session_Layer : Node '27' : [APP_CMD_HANDLER_FRAME : NodeID='27', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_ALARM', Command='0x02', Parameters: [0x1B, 0x05, 0x00, 0x00, 0x00]] DEBUG 2020-03-16 17:12:04,659 (Z-Wave): CCAlarmSensor : Node '27:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_REPORT : Type='WATER_LEAK_ALARM', State='0x00', Duration='0', SourceNodeID='27'] DEBUG 2020-03-16 17:12:05,840 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:05,840 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x70, 0x01, 0x98] DEBUG 2020-03-16 17:12:05,840 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x70, 0x01, 0x98] has been received. DEBUG 2020-03-16 17:12:05,840 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x70, 0x01, 0x98] (callback ID=0x70) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x70)' ERROR 2020-03-16 17:12:05,840 (Z-Wave): Node_Initializer : Node '9:2' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:05,856 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2] DEBUG 2020-03-16 17:12:05,856 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3] DEBUG 2020-03-16 17:12:05,856 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='1')] DEBUG 2020-03-16 17:12:05,856 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@629e474d'. DEBUG 2020-03-16 17:12:05,856 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@629e474d'. DEBUG 2020-03-16 17:12:05,856 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@629e474d'... DEBUG 2020-03-16 17:12:05,856 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x71)' request (callback ID=0x71). DEBUG 2020-03-16 17:12:05,856 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x26, 0x02, 0x05, 0x71, 0xD2]. DEBUG 2020-03-16 17:12:05,862 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:05,862 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x26, 0x02, 0x05, 0x71, 0xD2] transmission OK. DEBUG 2020-03-16 17:12:05,862 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x71)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:05,880 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:05,880 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:05,880 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:05,880 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x71). DEBUG 2020-03-16 17:12:05,880 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x71). DEBUG 2020-03-16 17:12:07,023 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:07,023 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x71, 0x01, 0x99] DEBUG 2020-03-16 17:12:07,023 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x71, 0x01, 0x99] has been received. DEBUG 2020-03-16 17:12:07,023 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x71, 0x01, 0x99] (callback ID=0x71) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x71)' ERROR 2020-03-16 17:12:07,023 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:07,035 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='2')] DEBUG 2020-03-16 17:12:07,035 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6392c7ee'. DEBUG 2020-03-16 17:12:07,035 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6392c7ee'. DEBUG 2020-03-16 17:12:07,035 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@6392c7ee'... DEBUG 2020-03-16 17:12:07,035 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x72)' request (callback ID=0x72). DEBUG 2020-03-16 17:12:07,035 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x26, 0x02, 0x05, 0x72, 0xD1]. DEBUG 2020-03-16 17:12:07,035 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:07,035 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x26, 0x02, 0x05, 0x72, 0xD1] transmission OK. DEBUG 2020-03-16 17:12:07,035 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x72)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:07,066 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:07,077 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:07,077 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:07,077 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x72). DEBUG 2020-03-16 17:12:07,077 (Z-Wave): RX_Session_Layer : Waiting 64 960 [ms] for 'ZW_SendData' callback frame (callback ID=0x72). DEBUG 2020-03-16 17:12:07,504 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:07,504 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0B, 0x00, 0x04, 0x00, 0x45, 0x05, 0x20, 0x03, 0xFF, 0xFF, 0xFE, 0x6D] DEBUG 2020-03-16 17:12:07,504 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0B, 0x00, 0x04, 0x00, 0x45, 0x05, 0x20, 0x03, 0xFF, 0xFF, 0xFE, 0x6D] has been received. DEBUG 2020-03-16 17:12:07,504 (Z-Wave): Node_Initializer : Node '69:0' : [COMMAND_CLASS_BASIC::BASIC_REPORT (Retry='1'), Value='255'] DEBUG 2020-03-16 17:12:07,504 (Z-Wave): RX_Session_Layer : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=69, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x6D) INFO 2020-03-16 17:12:07,504 (Z-Wave): Node '69' : Node [Manufacturer='Poly-control', Type='Unknown'] has been successfully initialized. DEBUG 2020-03-16 17:12:07,519 (Z-Wave): Node : Node '69' : Node has been successfully initialized with the following command classes... DEBUG 2020-03-16 17:12:07,519 (Z-Wave): Node : Node '69' : --> 'COMMAND_CLASS_BASIC', 'supported'. DEBUG 2020-03-16 17:12:07,519 (Z-Wave): Node : Node '69' : --> 'COMMAND_CLASS_MANUFACTURER_SPECIFIC', 'supported'. DEBUG 2020-03-16 17:12:07,519 (Z-Wave): Node : Node '69' : --> 'COMMAND_CLASS_ZWAVEPLUS_INFO', 'supported'. DEBUG 2020-03-16 17:12:08,021 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:08,021 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x72, 0x01, 0x9A] DEBUG 2020-03-16 17:12:08,021 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x72, 0x01, 0x9A] has been received. DEBUG 2020-03-16 17:12:08,021 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x72, 0x01, 0x9A] (callback ID=0x72) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x72)' ERROR 2020-03-16 17:12:08,021 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SWITCH_MULTILEVEL_V3::SWITCH_MULTILEVEL_GET (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:08,037 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_METER_V3] DEBUG 2020-03-16 17:12:08,037 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_METER::METER_GET (Retry='1'), Scale='ELECTRIC_METER_SCALE_W'] DEBUG 2020-03-16 17:12:08,037 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1ff7ee3a'. DEBUG 2020-03-16 17:12:08,037 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1ff7ee3a'. DEBUG 2020-03-16 17:12:08,037 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1ff7ee3a'... DEBUG 2020-03-16 17:12:08,037 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x73)' request (callback ID=0x73). DEBUG 2020-03-16 17:12:08,037 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0E, 0x00, 0x13, 0x09, 0x07, 0x60, 0x0D, 0x00, 0x01, 0x32, 0x01, 0x10, 0x05, 0x73, 0xD5]. DEBUG 2020-03-16 17:12:08,044 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:08,044 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0E, 0x00, 0x13, 0x09, 0x07, 0x60, 0x0D, 0x00, 0x01, 0x32, 0x01, 0x10, 0x05, 0x73, 0xD5] transmission OK. DEBUG 2020-03-16 17:12:08,044 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x73)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:08,062 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:08,062 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:08,062 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:08,062 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x73). DEBUG 2020-03-16 17:12:08,062 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x73). DEBUG 2020-03-16 17:12:09,744 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:09,744 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x1B, 0x07, 0x9C, 0x02, 0x1B, 0x05, 0x00, 0x00, 0x00, 0x6A] DEBUG 2020-03-16 17:12:09,744 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x1B, 0x07, 0x9C, 0x02, 0x1B, 0x05, 0x00, 0x00, 0x00, 0x6A] has been received. DEBUG 2020-03-16 17:12:09,744 (Z-Wave): RX_Session_Layer : Node '27' : [APP_CMD_HANDLER_FRAME : NodeID='27', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_ALARM', Command='0x02', Parameters: [0x1B, 0x05, 0x00, 0x00, 0x00]] DEBUG 2020-03-16 17:12:09,744 (Z-Wave): CCAlarmSensor : Node '27:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_REPORT : Type='WATER_LEAK_ALARM', State='0x00', Duration='0', SourceNodeID='27'] DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x73, 0x01, 0x9B] DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x73, 0x01, 0x9B] has been received. DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x73, 0x01, 0x9B] (callback ID=0x73) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x73)' ERROR 2020-03-16 17:12:09,812 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_METER::METER_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:09,812 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_METER::METER_GET (Retry='2'), Scale='ELECTRIC_METER_SCALE_W'] DEBUG 2020-03-16 17:12:09,812 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@76e4dcd4'. DEBUG 2020-03-16 17:12:09,812 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@76e4dcd4'. DEBUG 2020-03-16 17:12:09,812 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@76e4dcd4'... DEBUG 2020-03-16 17:12:09,812 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x74)' request (callback ID=0x74). DEBUG 2020-03-16 17:12:09,812 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0E, 0x00, 0x13, 0x09, 0x07, 0x60, 0x0D, 0x00, 0x01, 0x32, 0x01, 0x10, 0x05, 0x74, 0xD2]. DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0E, 0x00, 0x13, 0x09, 0x07, 0x60, 0x0D, 0x00, 0x01, 0x32, 0x01, 0x10, 0x05, 0x74, 0xD2] transmission OK. DEBUG 2020-03-16 17:12:09,812 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x74)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:09,841 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:09,841 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:09,841 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:09,841 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x74). DEBUG 2020-03-16 17:12:09,841 (Z-Wave): RX_Session_Layer : Waiting 64 974 [ms] for 'ZW_SendData' callback frame (callback ID=0x74). DEBUG 2020-03-16 17:12:10,065 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:10,065 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x1F, 0x07, 0x9C, 0x02, 0x1F, 0x05, 0x00, 0x00, 0x00, 0x6A] DEBUG 2020-03-16 17:12:10,065 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x1F, 0x07, 0x9C, 0x02, 0x1F, 0x05, 0x00, 0x00, 0x00, 0x6A] has been received. DEBUG 2020-03-16 17:12:10,065 (Z-Wave): RX_Session_Layer : Node '31' : [APP_CMD_HANDLER_FRAME : NodeID='31', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_ALARM', Command='0x02', Parameters: [0x1F, 0x05, 0x00, 0x00, 0x00]] DEBUG 2020-03-16 17:12:10,065 (Z-Wave): CCAlarmSensor : Node '31:0' : [COMMAND_CLASS_SENSOR_ALARM::SENSOR_ALARM_REPORT : Type='WATER_LEAK_ALARM', State='0x00', Duration='0', SourceNodeID='31'] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x74, 0x01, 0x9C] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x74, 0x01, 0x9C] has been received. DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x74, 0x01, 0x9C] (callback ID=0x74) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x32, 0x01, 0x10], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x74)' ERROR 2020-03-16 17:12:10,960 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_METER::METER_GET (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:10,960 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_ALARM_V2] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='1'), AlarmType='ALARM_TYPE_SYSTEM'] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@66d0596a'. DEBUG 2020-03-16 17:12:10,960 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@66d0596a'. DEBUG 2020-03-16 17:12:10,960 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@66d0596a'... DEBUG 2020-03-16 17:12:10,960 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x75)' request (callback ID=0x75). DEBUG 2020-03-16 17:12:10,960 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0F, 0x00, 0x13, 0x09, 0x08, 0x60, 0x0D, 0x00, 0x01, 0x71, 0x04, 0x00, 0x09, 0x05, 0x75, 0x82]. DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0F, 0x00, 0x13, 0x09, 0x08, 0x60, 0x0D, 0x00, 0x01, 0x71, 0x04, 0x00, 0x09, 0x05, 0x75, 0x82] transmission OK. DEBUG 2020-03-16 17:12:10,960 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x75)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:10,992 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:10,992 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:10,992 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:10,993 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x75). DEBUG 2020-03-16 17:12:10,993 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x75). DEBUG 2020-03-16 17:12:12,002 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:12,002 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x75, 0x01, 0x9D] DEBUG 2020-03-16 17:12:12,002 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x75, 0x01, 0x9D] has been received. DEBUG 2020-03-16 17:12:12,002 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x75, 0x01, 0x9D] (callback ID=0x75) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x75)' ERROR 2020-03-16 17:12:12,002 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:12,018 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_ALARM_V2::ALARM_GET, (Retry='2'), AlarmType='ALARM_TYPE_SYSTEM'] DEBUG 2020-03-16 17:12:12,018 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@13085fe9'. DEBUG 2020-03-16 17:12:12,018 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@13085fe9'. DEBUG 2020-03-16 17:12:12,018 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@13085fe9'... DEBUG 2020-03-16 17:12:12,018 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x76)' request (callback ID=0x76). DEBUG 2020-03-16 17:12:12,018 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0F, 0x00, 0x13, 0x09, 0x08, 0x60, 0x0D, 0x00, 0x01, 0x71, 0x04, 0x00, 0x09, 0x05, 0x76, 0x81]. DEBUG 2020-03-16 17:12:12,018 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:12,018 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0F, 0x00, 0x13, 0x09, 0x08, 0x60, 0x0D, 0x00, 0x01, 0x71, 0x04, 0x00, 0x09, 0x05, 0x76, 0x81] transmission OK. DEBUG 2020-03-16 17:12:12,018 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x76)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:12,039 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:12,039 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:12,039 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:12,039 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x76). DEBUG 2020-03-16 17:12:12,039 (Z-Wave): RX_Session_Layer : Waiting 64 971 [ms] for 'ZW_SendData' callback frame (callback ID=0x76). DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x76, 0x01, 0x9E] DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x76, 0x01, 0x9E] has been received. DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x76, 0x01, 0x9E] (callback ID=0x76) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x71, 0x04, 0x00, 0x09], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x76)' ERROR 2020-03-16 17:12:12,952 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_ALARM_V2::ALARM_REPORT (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:12,952 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL] DEBUG 2020-03-16 17:12:12,952 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')] DEBUG 2020-03-16 17:12:12,952 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@332ef58c'. DEBUG 2020-03-16 17:12:12,952 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@332ef58c'. DEBUG 2020-03-16 17:12:12,952 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@332ef58c'... DEBUG 2020-03-16 17:12:12,952 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x77)' request (callback ID=0x77). DEBUG 2020-03-16 17:12:12,952 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x77, 0xC5]. DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x77, 0xC5] transmission OK. DEBUG 2020-03-16 17:12:12,952 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x77)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:12,982 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:12,982 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:12,982 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:12,982 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x77). DEBUG 2020-03-16 17:12:12,982 (Z-Wave): RX_Session_Layer : Waiting 64 973 [ms] for 'ZW_SendData' callback frame (callback ID=0x77). DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x77, 0x01, 0x9F] DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x77, 0x01, 0x9F] has been received. DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x77, 0x01, 0x9F] (callback ID=0x77) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x77)' ERROR 2020-03-16 17:12:14,054 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:14,054 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='2')] DEBUG 2020-03-16 17:12:14,054 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@637c8d41'. DEBUG 2020-03-16 17:12:14,054 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@637c8d41'. DEBUG 2020-03-16 17:12:14,054 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@637c8d41'... DEBUG 2020-03-16 17:12:14,054 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x78)' request (callback ID=0x78). DEBUG 2020-03-16 17:12:14,054 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x78, 0xCA]. DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x78, 0xCA] transmission OK. DEBUG 2020-03-16 17:12:14,054 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x78)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:14,232 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:14,232 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:14,232 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:14,232 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x78). DEBUG 2020-03-16 17:12:14,232 (Z-Wave): RX_Session_Layer : Waiting 64 832 [ms] for 'ZW_SendData' callback frame (callback ID=0x78). DEBUG 2020-03-16 17:12:15,180 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:15,180 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x78, 0x01, 0x90] DEBUG 2020-03-16 17:12:15,180 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x78, 0x01, 0x90] has been received. DEBUG 2020-03-16 17:12:15,180 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x78, 0x01, 0x90] (callback ID=0x78) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x78)' ERROR 2020-03-16 17:12:15,180 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='2')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION] DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION_V2] DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC] DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Controller_API : Enqueue (WORKING-Queue, Count=1) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@703934d'. DEBUG 2020-03-16 17:12:15,196 (Z-Wave): Controller_API : Dequeue (WORKING-Queue, Count=0) Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@703934d'. DEBUG 2020-03-16 17:12:15,196 (Z-Wave): TX_Session_Layer : Executing Z-Wave function call 'org.openremote.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@703934d'... DEBUG 2020-03-16 17:12:15,196 (Z-Wave): TX_Session_Layer : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x79)' request (callback ID=0x79). DEBUG 2020-03-16 17:12:15,196 (Z-Wave): TX_Frame_Layer : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x20, 0x02, 0x05, 0x79, 0xDC]. DEBUG 2020-03-16 17:12:15,196 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x06] DEBUG 2020-03-16 17:12:15,196 (Z-Wave): RX_Frame_Layer : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x09, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x20, 0x02, 0x05, 0x79, 0xDC] transmission OK. DEBUG 2020-03-16 17:12:15,196 (Z-Wave): RX_Session_Layer : ACK -> Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x79)' has been successfully sent - waiting 5 000 [ms] for response frame. DEBUG 2020-03-16 17:12:15,369 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:15,369 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8] DEBUG 2020-03-16 17:12:15,369 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received. DEBUG 2020-03-16 17:12:15,369 (Z-Wave): RX_Session_Layer : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x79). DEBUG 2020-03-16 17:12:15,369 (Z-Wave): RX_Session_Layer : Waiting 64 829 [ms] for 'ZW_SendData' callback frame (callback ID=0x79). DEBUG 2020-03-16 17:12:16,421 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:16,421 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x05, 0x00, 0x13, 0x79, 0x01, 0x91] DEBUG 2020-03-16 17:12:16,421 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x05, 0x00, 0x13, 0x79, 0x01, 0x91] has been received. DEBUG 2020-03-16 17:12:16,421 (Z-Wave): RX_Session_Layer : Callback [0x01, 0x05, 0x00, 0x13, 0x79, 0x01, 0x91] (callback ID=0x79) with state 'TRANSMIT_COMPLETE_NO_ACK' received -> Failed to execute Z-Wave function 'ZW_SendData(NodeID=9, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x79)' ERROR 2020-03-16 17:12:16,421 (Z-Wave): Node_Initializer : Node '9:1' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')] failed : 'NO_ACK_ERROR' DEBUG 2020-03-16 17:12:16,421 (Z-Wave): Node_Initializer : Node '9' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2] INFO 2020-03-16 17:12:16,421 (Z-Wave): Node '9' : Node [Manufacturer='Fibargroup', Type='Unknown'] has been successfully initialized. DEBUG 2020-03-16 17:12:17,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:17,750 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x09, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0x96] DEBUG 2020-03-16 17:12:17,750 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x09, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0x96] has been received. DEBUG 2020-03-16 17:12:17,750 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00]] DEBUG 2020-03-16 17:12:17,751 (Z-Wave): Node : Node '9' : [COMMAND_CLASS_MULTI_CHANNEL::MULTI_CHANNEL_CMD_ENCAP] --> Unencapsualted : endpoint='1', [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x04, 0x22, 0x00, 0x00]]. DEBUG 2020-03-16 17:12:17,751 (Z-Wave): CCMultiLevelSensor : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='0', Type='POWER', Scale='POWER_SCALE_WATT']. DEBUG 2020-03-16 17:12:17,751 (Z-Wave): CCMultiLevelSensor : Node '9:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='0', Type='POWER', Scale='POWER_SCALE_WATT']. DEBUG 2020-03-16 17:12:20,691 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x01] DEBUG 2020-03-16 17:12:20,691 (Z-Wave): RX_Serial_Port_RXTX : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x09, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0x96] DEBUG 2020-03-16 17:12:20,691 (Z-Wave): RX_Frame_Layer : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x09, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00, 0x96] has been received. DEBUG 2020-03-16 17:12:20,691 (Z-Wave): RX_Session_Layer : Node '9' : [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x01, 0x00, 0x31, 0x05, 0x04, 0x22, 0x00, 0x00]] DEBUG 2020-03-16 17:12:20,691 (Z-Wave): Node : Node '9' : [COMMAND_CLASS_MULTI_CHANNEL::MULTI_CHANNEL_CMD_ENCAP] --> Unencapsualted : endpoint='1', [APP_CMD_HANDLER_FRAME : NodeID='9', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x04, 0x22, 0x00, 0x00]]. DEBUG 2020-03-16 17:12:20,691 (Z-Wave): CCMultiLevelSensor : Node '9:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='0', Type='POWER', Scale='POWER_SCALE_WATT']. DEBUG 2020-03-16 17:12:20,691 (Z-Wave): CCMultiLevelSensor : Node '9:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='0', Type='POWER', Scale='POWER_SCALE_WATT'].