2022-12-07 07:37:02.970 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 07:37:02.976 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 07:37:02.976 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 07:37:02.976 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 07:37:02.977 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:37:02.977 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1914786ms 2022-12-07 07:37:02.977 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:37:02.977 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:37:02.977 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:37:02.991 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 07:37:02.991 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 07:37:02.991 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 07:37:02.991 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 07:37:02.991 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1920388ms 2022-12-07 07:37:02.991 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:37:02.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:37:02.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:37:02.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:37:03.190 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 07:37:03.211 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=8B], lastHopLqi=196, lastHopRssi=-51, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 07:37:03.212 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8B, rssi=-51, lqi=C4, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 07:37:03.213 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:37:03.213 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:37:03.213 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 07:37:03.214 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 07:37:03.214 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 07:37:03.215 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:37:03.216 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:37:03.216 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:37:03.216 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:37:03.665 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=8B], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 07:37:03.666 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 07:37:03.667 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 07:37:07.220 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=8C], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 07:37:07.220 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8C, rssi=-52, lqi=C0, payload=00 00 00 07 00] 2022-12-07 07:37:07.220 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:37:07.221 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:37:07.221 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 07:37:07.221 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 07:37:07.221 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:37:07.222 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 07:37:07.223 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=2, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:37:07.223 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 07:37:07.224 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 07:37:07.224 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 07:37:07.224 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=23, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 07:37:07.224 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 07:37:07.240 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=A9] 2022-12-07 07:37:07.312 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=A9], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 07:37:07.313 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 07:37:07.313 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 07:37:07.313 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=92, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:37:07.314 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 07:37:07.314 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 07:37:07.315 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 07:37:07.315 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 07:37:14.817 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=8D], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 07:37:14.817 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8D, rssi=-52, lqi=C0, payload=82 00 00] 2022-12-07 07:37:14.818 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:37:14.818 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:37:14.818 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 07:37:14.818 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 07:37:15.085 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 07:43:51.233 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling [zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:centerButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:rightButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:leftButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryLevel, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryVoltage] channels... 2022-12-07 07:49:01.177 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 07:49:01.178 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 07:49:01.178 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 07:49:01.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 07:49:01.179 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:49:01.179 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:49:01.179 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:49:01.179 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1865959ms 2022-12-07 07:49:01.180 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:49:01.198 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 07:49:01.198 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 07:49:01.199 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 07:49:01.199 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 07:49:01.199 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1941475ms 2022-12-07 07:49:01.199 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:49:01.199 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:49:01.199 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:49:01.200 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:49:01.403 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 07:49:01.424 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=42], lastHopLqi=196, lastHopRssi=-51, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 07:49:01.424 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=42, rssi=-51, lqi=C4, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 07:49:01.425 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:49:01.425 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:49:01.425 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 07:49:01.425 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 07:49:01.426 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 07:49:01.426 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 07:49:01.426 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 07:49:01.426 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 07:49:01.427 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 07:49:01.904 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=42], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 07:49:01.904 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 07:49:01.905 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 07:49:05.428 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=43], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 07:49:05.429 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=43, rssi=-52, lqi=C0, payload=00 00 00 07 00] 2022-12-07 07:49:05.430 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:49:05.430 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:49:05.431 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 07:49:05.431 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 07:49:05.431 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:49:05.431 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 07:49:05.432 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=1, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:49:05.432 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 07:49:05.432 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 07:49:05.432 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 07:49:05.433 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=24, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 07:49:05.433 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 07:49:05.449 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=AD] 2022-12-07 07:49:05.522 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=AD], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 07:49:05.522 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 07:49:05.522 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 07:49:05.523 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=92, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 07:49:05.523 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 07:49:05.523 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 07:49:05.523 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 07:49:05.523 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 07:49:13.025 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=44], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 07:49:13.026 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=44, rssi=-52, lqi=C0, payload=82 00 00] 2022-12-07 07:49:13.026 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 07:49:13.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 07:49:13.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 07:49:13.027 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 07:49:13.304 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 08:00:59.399 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 08:00:59.400 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 08:00:59.401 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:00:59.401 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:00:59.401 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:00:59.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:00:59.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:00:59.401 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1856867ms 2022-12-07 08:00:59.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:00:59.420 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 08:00:59.420 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 08:00:59.420 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:00:59.420 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:00:59.421 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:00:59.421 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1931616ms 2022-12-07 08:00:59.421 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:00:59.421 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:00:59.421 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:00:59.621 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:00:59.641 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=0D], lastHopLqi=196, lastHopRssi=-51, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:00:59.642 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=0D, rssi=-51, lqi=C4, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:00:59.642 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:00:59.642 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:00:59.642 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:00:59.643 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:00:59.643 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:00:59.643 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:00:59.643 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:00:59.644 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:00:59.644 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:01:00.118 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=0D], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:01:00.118 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:01:00.119 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:01:03.657 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0E], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 08:01:03.657 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=0E, rssi=-52, lqi=C0, payload=00 00 00 07 00] 2022-12-07 08:01:03.658 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:01:03.658 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:01:03.658 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 08:01:03.658 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 08:01:03.659 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:01:03.659 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 08:01:03.659 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=1, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:01:03.659 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 08:01:03.659 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 08:01:03.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 08:01:03.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=25, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 08:01:03.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 08:01:03.677 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=B1] 2022-12-07 08:01:03.847 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B1], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:01:03.847 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 08:01:03.848 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 08:01:03.848 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=190, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:01:03.848 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 08:01:03.849 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 08:01:03.849 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 08:01:03.849 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 08:01:11.247 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0F], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 08:01:11.247 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=0F, rssi=-52, lqi=C0, payload=82 00 00] 2022-12-07 08:01:11.248 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:01:11.248 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:01:11.248 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:01:11.248 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:01:11.522 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 08:12:57.575 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 08:12:57.576 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 08:12:57.576 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:12:57.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:12:57.577 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:12:57.577 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:12:57.577 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:12:57.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1878144ms 2022-12-07 08:12:57.578 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:12:57.596 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 08:12:57.596 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 08:12:57.596 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:12:57.596 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:12:57.597 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1869950ms 2022-12-07 08:12:57.597 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:12:57.597 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:12:57.597 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:12:57.597 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:12:57.794 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:12:57.814 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=1D], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:12:57.815 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=1D, rssi=-50, lqi=C8, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:12:57.815 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:12:57.815 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:12:57.815 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:12:57.815 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:12:57.816 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:12:57.816 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:12:57.816 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:12:57.816 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:12:57.816 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:12:58.317 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=1D], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:12:58.317 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:12:58.318 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:13:01.823 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=1E], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 08:13:01.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=1E, rssi=-50, lqi=C8, payload=00 00 00 07 00] 2022-12-07 08:13:01.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:13:01.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:13:01.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 08:13:01.825 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 08:13:01.825 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:13:01.825 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 08:13:01.825 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:13:01.825 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 08:13:01.826 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 08:13:01.826 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 08:13:01.826 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=26, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 08:13:01.826 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 08:13:01.844 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=B5] 2022-12-07 08:13:01.914 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B5], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:13:01.915 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 08:13:01.915 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 08:13:01.915 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=90, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:13:01.916 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 08:13:01.916 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 08:13:01.916 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 08:13:01.916 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 08:13:09.418 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=1F], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 08:13:09.418 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=1F, rssi=-50, lqi=C8, payload=82 00 00] 2022-12-07 08:13:09.418 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:13:09.419 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:13:09.419 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:13:09.419 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:13:09.690 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 08:24:55.716 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 08:24:55.721 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 08:24:55.722 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:24:55.722 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:24:55.723 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:24:55.723 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1862952ms 2022-12-07 08:24:55.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:24:55.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:24:55.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:24:55.737 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 08:24:55.737 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 08:24:55.737 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:24:55.738 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:24:55.738 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:24:55.738 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1951420ms 2022-12-07 08:24:55.738 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:24:55.738 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:24:55.738 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:24:55.940 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:24:55.960 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=49], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:24:55.961 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=49, rssi=-50, lqi=C8, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:24:55.961 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:24:55.962 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:24:55.962 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:24:55.962 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:24:55.963 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:24:55.964 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:24:55.964 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:24:55.964 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:24:55.964 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:24:55.981 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:24:55.981 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=4A], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:24:55.982 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4A, rssi=-50, lqi=C8, payload=82 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:24:55.982 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:24:55.982 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:24:55.982 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=82, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:24:55.982 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=82, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:24:55.983 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:24:55.983 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:24:55.983 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:24:55.983 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:24:55.984 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:24:56.438 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=49], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:24:56.439 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:24:56.440 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:24:56.459 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=4A], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:24:56.459 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:24:56.460 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:24:59.169 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4B], lastHopLqi=196, lastHopRssi=-51, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 08:24:59.170 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4B, rssi=-51, lqi=C4, payload=00 00 00 07 00] 2022-12-07 08:24:59.170 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:24:59.170 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:24:59.171 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 08:24:59.171 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 08:24:59.171 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:24:59.172 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 08:24:59.173 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=2, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:24:59.173 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 08:24:59.174 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 08:24:59.174 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 08:24:59.174 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=27, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 08:24:59.174 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 08:24:59.189 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=B9] 2022-12-07 08:24:59.262 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B9], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:24:59.263 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 08:24:59.263 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 08:24:59.263 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=92, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:24:59.264 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 08:24:59.264 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 08:24:59.265 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 08:24:59.265 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 08:25:07.572 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4C], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=83 00 00] 2022-12-07 08:25:07.572 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4C, rssi=-52, lqi=C0, payload=83 00 00] 2022-12-07 08:25:07.572 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:25:07.573 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:25:07.573 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=83, nwkAddrOfInterest=0000] 2022-12-07 08:25:07.573 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=83, nwkAddrOfInterest=0000] 2022-12-07 08:25:07.832 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 08:30:40.872 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling [zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:centerButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:rightButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:leftButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryLevel, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryVoltage] channels... 2022-12-07 08:36:53.883 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 08:36:53.884 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 08:36:53.885 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:36:53.885 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:36:53.885 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:36:53.885 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1835702ms 2022-12-07 08:36:53.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:36:53.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:36:53.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:36:53.904 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 08:36:53.904 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 08:36:53.905 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:36:53.905 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:36:53.905 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1978633ms 2022-12-07 08:36:53.905 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:36:53.905 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:36:53.905 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:36:53.906 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:36:54.099 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:36:54.120 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=A7], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:36:54.120 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=A7, rssi=-50, lqi=C8, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:36:54.121 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:36:54.121 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:36:54.121 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:36:54.121 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:36:54.122 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:36:54.122 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:36:54.122 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:36:54.122 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:36:54.122 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:36:54.608 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=A7], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:36:54.609 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:36:54.609 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:36:58.132 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=A8], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 08:36:58.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=A8, rssi=-50, lqi=C8, payload=00 00 00 07 00] 2022-12-07 08:36:58.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:36:58.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:36:58.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 08:36:58.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 08:36:58.134 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:36:58.134 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 08:36:58.134 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:36:58.134 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 08:36:58.134 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 08:36:58.134 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 08:36:58.135 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=28, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 08:36:58.135 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 08:36:58.153 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=BD] 2022-12-07 08:36:58.221 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BD], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:36:58.221 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 08:36:58.222 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 08:36:58.222 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=88, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:36:58.222 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 08:36:58.222 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 08:36:58.222 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 08:36:58.222 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 08:37:05.725 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=A9], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 08:37:05.725 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=A9, rssi=-50, lqi=C8, payload=82 00 00] 2022-12-07 08:37:05.726 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:37:05.726 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:37:05.726 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:37:05.726 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:37:05.996 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 08:48:52.062 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 08:48:52.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 08:48:52.063 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:48:52.063 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:48:52.063 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:48:52.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:48:52.063 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1954460ms 2022-12-07 08:48:52.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:48:52.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:48:52.083 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 08:48:52.083 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 08:48:52.083 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 08:48:52.083 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 08:48:52.084 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:48:52.084 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1861731ms 2022-12-07 08:48:52.084 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:48:52.084 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:48:52.084 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:48:52.278 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 08:48:52.299 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=B8], lastHopLqi=204, lastHopRssi=-49, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:48:52.299 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=B8, rssi=-49, lqi=CC, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 08:48:52.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:48:52.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:48:52.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:48:52.300 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 08:48:52.300 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 08:48:52.301 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 08:48:52.301 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 08:48:52.301 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 08:48:52.302 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 08:48:52.736 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=B8], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:48:52.736 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 08:48:52.737 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 08:48:56.312 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B9], lastHopLqi=208, lastHopRssi=-48, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 08:48:56.312 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=B9, rssi=-48, lqi=D0, payload=00 00 00 07 00] 2022-12-07 08:48:56.312 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:48:56.313 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:48:56.313 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 08:48:56.313 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 08:48:56.313 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:48:56.313 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 08:48:56.314 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=1, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:48:56.314 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 08:48:56.314 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 08:48:56.314 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 08:48:56.314 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=29, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 08:48:56.314 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 08:48:56.332 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=C1] 2022-12-07 08:48:56.404 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C1], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 08:48:56.404 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 08:48:56.405 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 08:48:56.405 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=92, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 08:48:56.405 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 08:48:56.405 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 08:48:56.406 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 08:48:56.406 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 08:49:03.907 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BA], lastHopLqi=208, lastHopRssi=-48, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 08:49:03.908 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=BA, rssi=-48, lqi=D0, payload=82 00 00] 2022-12-07 08:49:03.908 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 08:49:03.909 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 08:49:03.909 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:49:03.909 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 08:49:04.178 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 09:00:50.277 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 09:00:50.278 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 09:00:50.278 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:00:50.279 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:00:50.279 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:00:50.279 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:00:50.279 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:00:50.279 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1818501ms 2022-12-07 09:00:50.280 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:00:50.298 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 09:00:50.298 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 09:00:50.298 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:00:50.298 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:00:50.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1912365ms 2022-12-07 09:00:50.299 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:00:50.299 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:00:50.299 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:00:50.299 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:00:50.499 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 09:00:50.520 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=55], lastHopLqi=208, lastHopRssi=-48, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:00:50.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=55, rssi=-48, lqi=D0, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:00:50.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:00:50.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:00:50.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:00:50.521 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:00:50.522 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 09:00:50.522 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:00:50.522 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:00:50.522 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:00:50.522 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:00:51.021 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=55], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:00:51.022 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 09:00:51.022 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 09:00:54.532 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=56], lastHopLqi=208, lastHopRssi=-48, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 09:00:54.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=56, rssi=-48, lqi=D0, payload=00 00 00 07 00] 2022-12-07 09:00:54.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:00:54.533 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:00:54.533 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 09:00:54.533 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 09:00:54.533 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:00:54.533 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 09:00:54.534 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:00:54.534 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 09:00:54.534 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 09:00:54.534 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 09:00:54.534 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=2A, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 09:00:54.534 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 09:00:54.552 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=C5] 2022-12-07 09:00:54.622 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C5], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:00:54.623 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 09:00:54.623 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 09:00:54.624 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=90, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:00:54.624 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 09:00:54.624 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 09:00:54.624 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 09:00:54.624 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 09:01:02.132 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=57], lastHopLqi=208, lastHopRssi=-48, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 09:01:02.132 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=57, rssi=-48, lqi=D0, payload=82 00 00] 2022-12-07 09:01:02.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:01:02.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:01:02.133 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:01:02.133 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:01:02.400 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 09:02:42.319 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling [zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:centerButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:rightButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:leftButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryLevel, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryVoltage] channels... 2022-12-07 09:12:48.633 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 09:12:48.637 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 09:12:48.638 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:12:48.638 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:12:48.639 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:12:48.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1918232ms 2022-12-07 09:12:48.639 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:12:48.639 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:12:48.639 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:12:48.653 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 09:12:48.653 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 09:12:48.654 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:12:48.654 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:12:48.654 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1912996ms 2022-12-07 09:12:48.654 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:12:48.654 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:12:48.654 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:12:48.654 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:12:48.851 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 09:12:48.872 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=8B], lastHopLqi=184, lastHopRssi=-54, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:12:48.872 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8B, rssi=-54, lqi=B8, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:12:48.873 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:12:48.873 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:12:48.873 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:12:48.873 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:12:48.874 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 09:12:48.875 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:12:48.875 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:12:48.875 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:12:48.875 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:12:49.367 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=8B], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:12:49.368 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 09:12:49.369 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 09:12:52.884 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=8C], lastHopLqi=188, lastHopRssi=-53, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 09:12:52.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8C, rssi=-53, lqi=BC, payload=00 00 00 07 00] 2022-12-07 09:12:52.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:12:52.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:12:52.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 09:12:52.886 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 09:12:52.886 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:12:52.887 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 09:12:52.887 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=1, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:12:52.888 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 09:12:52.888 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 09:12:52.888 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 09:12:52.889 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=2B, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 09:12:52.889 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 09:12:52.905 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=C9] 2022-12-07 09:12:52.974 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C9], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:12:52.975 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 09:12:52.975 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 09:12:52.976 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=89, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:12:52.976 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 09:12:52.977 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 09:12:52.977 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 09:12:52.977 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 09:13:00.488 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=8D], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 09:13:00.489 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8D, rssi=-52, lqi=C0, payload=82 00 00] 2022-12-07 09:13:00.489 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:13:00.489 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:13:00.489 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:13:00.490 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:13:00.749 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH] 2022-12-07 09:14:25.090 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling [zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:centerButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:rightButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:leftButton, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryLevel, zigbee:tuya_ts0043:89abc0853a:bc33acfffeef6f95:batteryVoltage] channels... 2022-12-07 09:24:46.783 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspChildJoinHandler [networkId=0, index=0, joining=true, childId=751D, childEui64=BC33ACFFFEEF6F95, childType=EMBER_SLEEPY_END_DEVICE] 2022-12-07 09:24:46.784 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 751D. 2022-12-07 09:24:46.785 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:24:46.785 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:24:46.785 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:24:46.785 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:24:46.785 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:24:46.785 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1814998ms 2022-12-07 09:24:46.786 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:24:46.804 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspTrustCenterJoinHandler [networkId=0, newNodeId=751D, newNodeEui64=BC33ACFFFEEF6F95, status=EMBER_STANDARD_SECURITY_SECURED_REJOIN, policyDecision=EMBER_NO_ACTION, parentOfNewNodeId=0000] 2022-12-07 09:24:46.804 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: nodeStatusUpdate - node status is SECURED_REJOIN, network address is 751D. 2022-12-07 09:24:46.805 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device status updated. NWK=751D 2022-12-07 09:24:46.805 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling stopped 2022-12-07 09:24:46.805 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:24:46.805 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - BC33ACFFFEEF6F95: Polling initialised at 1805758ms 2022-12-07 09:24:46.805 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:24:46.806 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:24:46.806 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:24:47.006 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingSenderEui64Handler [networkId=0, senderEui64=BC33ACFFFEEF6F95] 2022-12-07 09:24:47.026 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=D8], lastHopLqi=200, lastHopRssi=-50, sender=751D, bindingIndex=255, addressIndex=255, messageContents=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:24:47.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=D8, rssi=-50, lqi=C8, payload=81 1D 75 95 6F EF FE FF AC 33 BC 80] 2022-12-07 09:24:47.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:24:47.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:24:47.028 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:24:47.028 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [751D/0 -> 0000/0, cluster=0013, TID=81, nwkAddrOfInterest=751D, ieeeAddr=BC33ACFFFEEF6F95, capability=80] 2022-12-07 09:24:47.028 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: Device announce received. From 751D, for 751D 2022-12-07 09:24:47.028 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - BC33ACFFFEEF6F95: NWK Discovery add node 751D 2022-12-07 09:24:47.029 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Updating node NWK=751D 2022-12-07 09:24:47.029 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=751D 2022-12-07 09:24:47.029 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=751D, endpoints=[]] 2022-12-07 09:24:47.519 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0000, clusterId=0013, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_SOURCE_EUI64], groupId=0, sequence=D8], messageTag=FF, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:24:47.520 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=FF state=RX_ACK 2022-12-07 09:24:47.520 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=FF, state=RX_ACK, outstanding=0 2022-12-07 09:24:51.037 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=D9], lastHopLqi=196, lastHopRssi=-51, sender=751D, bindingIndex=255, addressIndex=255, messageContents=00 00 00 07 00] 2022-12-07 09:24:51.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=D9, rssi=-51, lqi=C4, payload=00 00 00 07 00] 2022-12-07 09:24:51.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:24:51.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:24:51.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=00, commandId=0] 2022-12-07 09:24:51.039 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A 2022-12-07 09:24:51.039 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:24:51.039 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3 2022-12-07 09:24:51.039 [DEBUG] [transaction.ZigBeeTransactionManager] - 751D/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:24:51.039 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding 2022-12-07 09:24:51.040 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE] 2022-12-07 09:24:51.040 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=00, commandId=11] 2022-12-07 09:24:51.040 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=751D/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=2C, rssi=--, lqi=--, payload=18 00 0B 00 01] 2022-12-07 09:24:51.040 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 751D did not translate to command 2022-12-07 09:24:51.058 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=CD] 2022-12-07 09:24:51.127 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=751D, apsFrame=EmberApsFrame [profileId=0104, clusterId=000A, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=CD], messageTag=00, status=EMBER_SUCCESS, messageContents=] 2022-12-07 09:24:51.128 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK 2022-12-07 09:24:51.128 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=1 2022-12-07 09:24:51.128 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFEEF6F95 queueTime=89, state=COMPLETE, sendCnt=1, command=DefaultResponse [Time: 0000/0 -> 751D/1, cluster=000A, TID=00, commandIdentifier=0, statusCode=FAILURE]] 2022-12-07 09:24:51.128 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding 2022-12-07 09:24:51.129 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - BC33ACFFFEEF6F95: transactionComplete, state=COMPLETE, outstanding=0 2022-12-07 09:24:51.129 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3 2022-12-07 09:24:51.129 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=751D, TID=00, event=RX_ACK, state=COMPLETE 2022-12-07 09:24:58.633 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=0002, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=DA], lastHopLqi=192, lastHopRssi=-52, sender=751D, bindingIndex=255, addressIndex=255, messageContents=82 00 00] 2022-12-07 09:24:58.633 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=751D/0, destinationAddress=0000/0, profile=0000, cluster=0002, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=DA, rssi=-52, lqi=C0, payload=82 00 00] 2022-12-07 09:24:58.633 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node update. NWK Address=NULL 2022-12-07 09:24:58.634 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFEEF6F95: Node 751D is not updated from ZigBeeNode [state=ONLINE, IEEE=BC33ACFFFEEF6F95, NWK=----, endpoints=[]] 2022-12-07 09:24:58.634 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:24:58.634 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NodeDescriptorRequest [751D/0 -> 0000/0, cluster=0002, TID=82, nwkAddrOfInterest=0000] 2022-12-07 09:24:58.901 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFEEF6F95, status=EMBER_EEPROM_MFG_VERSION_MISMATCH]