Dear all,
all my SNZB-02 (those are temperature and humidity sensors) report rather frequently the following error
[ERROR] [verter.ZigBeeConverterBatteryPercent] - 00124B00251E3049: Error 0xffff setting server binding
The channels on temperature and humidity are definitely working. The battery level channel is still at 100%, after more than 6 month of use. Well Zigbee is power efficient, but maybe the channel is not working after all? Do I need to worry?
I activated the logging like recommended on the zigbee binding page and pasted parts of log until reching the error:
Log Snapshot
- openHab 4.0.3
- openjdk 17
- Raspberry Pi 4 Model B Rev 1.1
2023-10-06 10:04:34.695 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:34.696 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A3, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2023-10-06 10:04:34.697 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=A3, commandId=12]
2023-10-06 10:04:34.698 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=AC1E/1, profile=0104, cluster=0001, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=34, rssi=--, lqi=--, payload=00 A3 0C 00 00 0A]
2023-10-06 10:04:34.701 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AC1E, TID=A3, event=RX_NAK, state=DISPATCHED
2023-10-06 10:04:34.710 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=0B]
2023-10-06 10:04:34.913 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=9B44]
2023-10-06 10:04:34.933 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=9B44]
2023-10-06 10:04:35.719 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=CB91]
2023-10-06 10:04:35.740 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=CB91]
2023-10-06 10:04:35.963 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=AC1E]
2023-10-06 10:04:35.984 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=AC1E]
2023-10-06 10:04:36.510 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=9B44]
2023-10-06 10:04:36.530 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=9B44]
2023-10-06 10:04:36.835 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=9B44, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=09], messageTag=A4, status=EMBER_DELIVERY_FAILED, messageContents=]
2023-10-06 10:04:36.837 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A4 state=RX_NAK
2023-10-06 10:04:36.838 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A4, state=RX_NAK, outstanding=5
2023-10-06 10:04:36.839 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B0029174370 queueTime=4820, state=FAILED, sendCnt=1, command=BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]]
2023-10-06 10:04:36.840 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:36.841 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B0029174370: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:36.842 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B0029174370: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=00124B0029174370 queueTime=4822, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]]
2023-10-06 10:04:36.843 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=0/3
2023-10-06 10:04:36.844 [DEBUG] [transaction.ZigBeeTransactionManager] - 9B44/0: Sending ZigBeeTransaction [ieeeAddress=00124B0029174370 queueTime=4825, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]]
2023-10-06 10:04:36.845 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:36.846 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]
2023-10-06 10:04:36.848 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=9B44/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=35, rssi=--, lqi=--, payload=A4 70 43 17 29 00 4B 12 00 01 01 00 03 C4 79 25 FE FF 95 8E DC 01]
2023-10-06 10:04:36.849 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=9B44, TID=A4, event=RX_NAK, state=DISPATCHED
2023-10-06 10:04:36.860 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=0C]
2023-10-06 10:04:37.304 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=CB91]
2023-10-06 10:04:37.312 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B002919FD62 queueTime=22120, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 9198/0, cluster=0000, TID=A1, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]]
2023-10-06 10:04:37.313 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:37.313 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002919FD62: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:37.315 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002919FD62: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B002919FD62 queueTime=22123, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 9198/0, cluster=0000, TID=A1, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]]
2023-10-06 10:04:37.315 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=0/3
2023-10-06 10:04:37.317 [DEBUG] [transaction.ZigBeeTransactionManager] - 9198/0: Sending ZigBeeTransaction [ieeeAddress=00124B002919FD62 queueTime=22125, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> 9198/0, cluster=0000, TID=A1, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]]
2023-10-06 10:04:37.318 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:37.318 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=22256, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFD/0, cluster=0000, TID=A2, ieeeAddr=A4C138847CA182FD, requestType=0, startIndex=0]]
2023-10-06 10:04:37.319 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> 9198/0, cluster=0000, TID=A1, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]
2023-10-06 10:04:37.319 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:37.320 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=9198/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=36, rssi=--, lqi=--, payload=A1 62 FD 19 29 00 4B 12 00 00 00]
2023-10-06 10:04:37.321 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:37.322 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete exceeded max retries 1
2023-10-06 10:04:37.323 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=22261, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFD/0, cluster=0000, TID=A2, ieeeAddr=A4C138847CA182FD, requestType=0, startIndex=0]]
2023-10-06 10:04:37.324 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - A4C138847CA182FD: NWK Discovery NetworkAddressRequest returned from null
2023-10-06 10:04:37.324 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=CB91]
2023-10-06 10:04:37.325 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - A4C138847CA182FD: NWK Discovery node rediscovery request failed.
2023-10-06 10:04:37.326 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=22264, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFD/0, cluster=0000, TID=A2, ieeeAddr=A4C138847CA182FD, requestType=0, startIndex=0]]
2023-10-06 10:04:37.326 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - A4C138847CA182FD: NWK Discovery finishing node rediscovery
2023-10-06 10:04:37.327 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00124B002911508C: NWK Discovery starting node rediscovery
2023-10-06 10:04:37.327 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:37.328 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 00124B002911508C: NWK Discovery: Rediscovery using unicast to 619B
2023-10-06 10:04:37.329 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2023-10-06 10:04:37.330 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=1/3
2023-10-06 10:04:37.331 [DEBUG] [transaction.ZigBeeTransactionManager] - FFFF/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=10017, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=A6, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]]
2023-10-06 10:04:37.332 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:37.334 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=A6, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]
2023-10-06 10:04:37.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=37, rssi=--, lqi=--, payload=A6 62 FD 19 29 00 4B 12 00 00 00]
2023-10-06 10:04:37.337 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=5, outstandingQueues=0, sleepy=1/3
2023-10-06 10:04:37.338 [DEBUG] [transaction.ZigBeeTransactionManager] - 00124B002911508C: Creating new Transaction Queue
2023-10-06 10:04:37.339 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911508C: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
2023-10-06 10:04:37.340 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911508C: Updated sleepy state from false to true
2023-10-06 10:04:37.342 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911508C: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B002911508C queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 619B/0, cluster=0000, TID=--, ieeeAddr=00124B002911508C, requestType=0, startIndex=0]]
2023-10-06 10:04:37.343 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=5, outstandingQueues=1, sleepy=1/3
2023-10-06 10:04:37.345 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=0D]
2023-10-06 10:04:37.365 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendBroadcastResponse [networkId=0, status=EMBER_SUCCESS, sequence=0E]
2023-10-06 10:04:37.366 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=9198, sourceEui=00124B002919FD62, lastHopLqi=192, lastHopRssi=-52, relayList=9D71]
2023-10-06 10:04:37.387 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0000, clusterId=0000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0E], lastHopLqi=255, lastHopRssi=0, sender=0000, bindingIndex=255, addressIndex=255, messageContents=A6 62 FD 19 29 00 4B 12 00 00 00]
2023-10-06 10:04:37.388 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=9D71, sourceEui=0C4314FFFE11B5CC, lastHopLqi=192, lastHopRssi=-52, relayList=]
2023-10-06 10:04:37.405 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=9D71, sourceEui=0C4314FFFE11B5CC, lastHopLqi=192, lastHopRssi=-52, relayList=]
2023-10-06 10:04:37.427 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=9198, apsFrame=EmberApsFrame [profileId=0000, clusterId=0000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0D], messageTag=A1, status=EMBER_SUCCESS, messageContents=]
2023-10-06 10:04:37.428 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A1 state=RX_ACK
2023-10-06 10:04:37.429 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A1, state=RX_ACK, outstanding=5
2023-10-06 10:04:37.429 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4D], lastHopLqi=192, lastHopRssi=-52, sender=9D71, bindingIndex=255, addressIndex=255, messageContents=A1 00 62 FD 19 29 00 4B 12 00 98 91]
2023-10-06 10:04:37.429 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=9198, TID=A1, event=RX_ACK, state=ACKED
2023-10-06 10:04:37.430 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=9D71/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4D, rssi=-52, lqi=C0, payload=A1 00 62 FD 19 29 00 4B 12 00 98 91]
2023-10-06 10:04:37.431 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0C4314FFFE11B5CC: Node update. NWK Address=NULL
2023-10-06 10:04:37.432 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0C4314FFFE11B5CC: Node 9D71 is not updated from ZigBeeNode [state=ONLINE, IEEE=0C4314FFFE11B5CC, NWK=----, endpoints=[]]
2023-10-06 10:04:37.433 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [9D71/0 -> 0000/0, cluster=8000, TID=A1, status=SUCCESS, ieeeAddrRemoteDev=00124B002919FD62, nwkAddrRemoteDev=9198, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.434 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [9D71/0 -> 0000/0, cluster=8000, TID=A1, status=SUCCESS, ieeeAddrRemoteDev=00124B002919FD62, nwkAddrRemoteDev=9198, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.459 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4E], lastHopLqi=192, lastHopRssi=-52, sender=9D71, bindingIndex=255, addressIndex=255, messageContents=A6 00 62 FD 19 29 00 4B 12 00 98 91]
2023-10-06 10:04:37.460 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=9D71/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4E, rssi=-52, lqi=C0, payload=A6 00 62 FD 19 29 00 4B 12 00 98 91]
2023-10-06 10:04:37.461 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0C4314FFFE11B5CC: Node update. NWK Address=NULL
2023-10-06 10:04:37.462 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0C4314FFFE11B5CC: Node 9D71 is not updated from ZigBeeNode [state=ONLINE, IEEE=0C4314FFFE11B5CC, NWK=----, endpoints=[]]
2023-10-06 10:04:37.464 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [9D71/0 -> 0000/0, cluster=8000, TID=A6, status=SUCCESS, ieeeAddrRemoteDev=00124B002919FD62, nwkAddrRemoteDev=9198, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.464 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [9D71/0 -> 0000/0, cluster=8000, TID=A6, status=SUCCESS, ieeeAddrRemoteDev=00124B002919FD62, nwkAddrRemoteDev=9198, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.466 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=10151, state=COMPLETE, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=A6, ieeeAddr=00124B002919FD62, requestType=0, startIndex=0]]
2023-10-06 10:04:37.466 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00124B002919FD62: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [9D71/0 -> 0000/0, cluster=8000, TID=A6, status=SUCCESS, ieeeAddrRemoteDev=00124B002919FD62, nwkAddrRemoteDev=9198, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.467 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:37.467 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00124B002919FD62: Node SVC Discovery: NetworkAddressRequest confirmed by broadcast
2023-10-06 10:04:37.467 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=COMPLETE, outstanding=0
2023-10-06 10:04:37.468 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00124B002919FD62: Node SVC Discovery: request NWK_ADDRESS successful. Advancing to null.
2023-10-06 10:04:37.468 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=0, sleepy=1/3
2023-10-06 10:04:37.468 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00124B0029114F51: Node SVC Discovery: running NWK_ADDRESS
2023-10-06 10:04:37.471 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B0029114F51: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B0029114F51 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> BCED/0, cluster=0000, TID=--, ieeeAddr=00124B0029114F51, requestType=0, startIndex=0]]
2023-10-06 10:04:37.472 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=1/3
2023-10-06 10:04:37.473 [DEBUG] [transaction.ZigBeeTransactionManager] - BCED/0: Sending ZigBeeTransaction [ieeeAddress=00124B0029114F51 queueTime=2, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> BCED/0, cluster=0000, TID=A7, ieeeAddr=00124B0029114F51, requestType=0, startIndex=0]]
2023-10-06 10:04:37.474 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:37.474 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> BCED/0, cluster=0000, TID=A7, ieeeAddr=00124B0029114F51, requestType=0, startIndex=0]
2023-10-06 10:04:37.476 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=BCED/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=38, rssi=--, lqi=--, payload=A7 51 4F 11 29 00 4B 12 00 00 00]
2023-10-06 10:04:37.487 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=0F]
2023-10-06 10:04:37.520 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=BCED, sourceEui=00124B0029114F51, lastHopLqi=204, lastHopRssi=-49, relayList=AEED]
2023-10-06 10:04:37.541 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=AEED, sourceEui=9035EAFFFE5AD344, lastHopLqi=188, lastHopRssi=-53, relayList=]
2023-10-06 10:04:37.564 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=BCED, apsFrame=EmberApsFrame [profileId=0000, clusterId=0000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0F], messageTag=A7, status=EMBER_SUCCESS, messageContents=]
2023-10-06 10:04:37.564 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A7 state=RX_ACK
2023-10-06 10:04:37.565 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A7, state=RX_ACK, outstanding=5
2023-10-06 10:04:37.566 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=BCED, TID=A7, event=RX_ACK, state=ACKED
2023-10-06 10:04:37.584 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=4B], lastHopLqi=204, lastHopRssi=-49, sender=AEED, bindingIndex=255, addressIndex=255, messageContents=A7 00 51 4F 11 29 00 4B 12 00 ED BC]
2023-10-06 10:04:37.586 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=AEED/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=4B, rssi=-49, lqi=CC, payload=A7 00 51 4F 11 29 00 4B 12 00 ED BC]
2023-10-06 10:04:37.587 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 9035EAFFFE5AD344: Node update. NWK Address=NULL
2023-10-06 10:04:37.588 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 9035EAFFFE5AD344: Node AEED is not updated from ZigBeeNode [state=ONLINE, IEEE=9035EAFFFE5AD344, NWK=----, endpoints=[]]
2023-10-06 10:04:37.589 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [AEED/0 -> 0000/0, cluster=8000, TID=A7, status=SUCCESS, ieeeAddrRemoteDev=00124B0029114F51, nwkAddrRemoteDev=BCED, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.589 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [AEED/0 -> 0000/0, cluster=8000, TID=A7, status=SUCCESS, ieeeAddrRemoteDev=00124B0029114F51, nwkAddrRemoteDev=BCED, startIndex=null, nwkAddrAssocDevList=[]]
2023-10-06 10:04:37.604 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=AC1E]
2023-10-06 10:04:37.605 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=AC1E]
2023-10-06 10:04:38.108 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=9B44]
2023-10-06 10:04:38.128 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=9B44]
2023-10-06 10:04:38.353 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_BROADCAST, indexOrDestination=FFFF, apsFrame=EmberApsFrame [profileId=0000, clusterId=0000, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0E], messageTag=A6, status=EMBER_SUCCESS, messageContents=]
2023-10-06 10:04:38.355 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A6 state=RX_ACK
2023-10-06 10:04:38.356 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A6, state=RX_ACK, outstanding=5
2023-10-06 10:04:38.374 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=CB84, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=0A], messageTag=A5, status=EMBER_DELIVERY_FAILED, messageContents=]
2023-10-06 10:04:38.375 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A5 state=RX_NAK
2023-10-06 10:04:38.376 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A5, state=RX_NAK, outstanding=5
2023-10-06 10:04:38.377 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=9035EAFFFE892BAD queueTime=14451, state=FAILED, sendCnt=1, command=BindRequest [0000/0 -> CB84/0, cluster=0021, TID=A5, bindingTableEntry=BindingTable [srcAddr=9035EAFFFE892BAD/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0006]]]
2023-10-06 10:04:38.378 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:38.379 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 9035EAFFFE892BAD: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:38.380 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 9035EAFFFE892BAD: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=9035EAFFFE892BAD queueTime=14454, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> CB84/0, cluster=0021, TID=A5, bindingTableEntry=BindingTable [srcAddr=9035EAFFFE892BAD/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0006]]]
2023-10-06 10:04:38.381 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=2/3
2023-10-06 10:04:38.383 [DEBUG] [transaction.ZigBeeTransactionManager] - CB84/0: Sending ZigBeeTransaction [ieeeAddress=9035EAFFFE892BAD queueTime=14456, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> CB84/0, cluster=0021, TID=A5, bindingTableEntry=BindingTable [srcAddr=9035EAFFFE892BAD/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0006]]]
2023-10-06 10:04:38.383 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:38.385 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> CB84/0, cluster=0021, TID=A5, bindingTableEntry=BindingTable [srcAddr=9035EAFFFE892BAD/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0006]]
2023-10-06 10:04:38.386 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=CB84/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=39, rssi=--, lqi=--, payload=A5 AD 2B 89 FE FF EA 35 90 01 06 00 03 C4 79 25 FE FF 95 8E DC 01]
2023-10-06 10:04:38.387 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=CB84, TID=A5, event=RX_NAK, state=DISPATCHED
2023-10-06 10:04:38.399 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=10]
2023-10-06 10:04:38.900 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=CB91]
2023-10-06 10:04:38.921 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=CB91]
2023-10-06 10:04:39.195 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=AC1E]
2023-10-06 10:04:39.215 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=AC1E]
2023-10-06 10:04:39.506 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=AC1E, apsFrame=EmberApsFrame [profileId=0104, clusterId=0001, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0B], messageTag=A3, status=EMBER_DELIVERY_FAILED, messageContents=]
2023-10-06 10:04:39.507 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A3 state=RX_NAK
2023-10-06 10:04:39.508 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A3, state=RX_NAK, outstanding=5
2023-10-06 10:04:39.509 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B002911524A queueTime=9637, state=FAILED, sendCnt=2, command=DiscoverAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A3, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]]
2023-10-06 10:04:39.510 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:39.511 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911524A: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:39.512 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911524A: transactionComplete exceeded max retries 2
2023-10-06 10:04:39.513 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=00124B002911524A queueTime=9640, state=CANCELLED, sendCnt=2, command=DiscoverAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A3, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]]
2023-10-06 10:04:39.514 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B002911524A queueTime=9641, state=CANCELLED, sendCnt=2, command=DiscoverAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A3, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]]
2023-10-06 10:04:39.516 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:39.517 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911524A: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2023-10-06 10:04:39.518 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=0, sleepy=1/3
2023-10-06 10:04:39.519 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=0, sleepy=0/3
2023-10-06 10:04:39.520 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=AC1E, TID=A3, event=RX_NAK, state=CANCELLED
2023-10-06 10:04:39.521 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002911524A: Dynamically created 4 channels
2023-10-06 10:04:39.522 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B002911524A: Device initialization will be skipped as the device is already initialized
2023-10-06 10:04:39.524 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=Power Configuration, id=33, name=Battery Percentage Remaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=198, lastReportTime=Fri Oct 06 09:23:17 CEST 2023, implemented=false]
2023-10-06 10:04:39.525 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B002911524A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B002911524A queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=--, identifiers=[33]]]
2023-10-06 10:04:39.526 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=1, sleepy=0/3
2023-10-06 10:04:39.528 [DEBUG] [transaction.ZigBeeTransactionManager] - AC1E/1: Sending ZigBeeTransaction [ieeeAddress=00124B002911524A queueTime=2, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A8, identifiers=[33]]]
2023-10-06 10:04:39.529 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
2023-10-06 10:04:39.530 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Power Configuration: 0000/0 -> AC1E/1, cluster=0001, TID=A8, identifiers=[33]]
2023-10-06 10:04:39.530 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=A8, commandId=0]
2023-10-06 10:04:39.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=AC1E/1, profile=0104, cluster=0001, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=3A, rssi=--, lqi=--, payload=00 A8 00 21 00]
2023-10-06 10:04:39.543 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=11]
2023-10-06 10:04:39.748 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=9B44]
2023-10-06 10:04:39.768 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=9B44]
2023-10-06 10:04:40.792 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=AC1E]
2023-10-06 10:04:40.812 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=AC1E]
2023-10-06 10:04:41.344 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingNetworkStatusHandler [networkId=0, errorCode=6, target=9B44]
2023-10-06 10:04:41.364 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=9B44]
2023-10-06 10:04:41.470 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=CF69, sourceEui=A4C138990AC76315, lastHopLqi=144, lastHopRssi=-64, relayList=]
2023-10-06 10:04:41.491 [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=12], lastHopLqi=140, lastHopRssi=-65, sender=CF69, bindingIndex=255, addressIndex=255, messageContents=00 74 00 07 00]
2023-10-06 10:04:41.492 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=CF69/1, destinationAddress=0000/1, profile=0104, cluster=000A, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=12, rssi=-65, lqi=8C, payload=00 74 00 07 00]
2023-10-06 10:04:41.493 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - A4C138990AC76315: Node update. NWK Address=NULL
2023-10-06 10:04:41.494 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - A4C138990AC76315: Node CF69 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C138990AC76315, NWK=----, endpoints=[]]
2023-10-06 10:04:41.496 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=74, commandId=0]
2023-10-06 10:04:41.496 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Unsupported local server cluster 000A
2023-10-06 10:04:41.498 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - A4C138990AC76315: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C138990AC76315 queueTime=1, state=WAITING, sendCnt=0, command=DefaultResponse [Time: 0000/0 -> CF69/1, cluster=000A, TID=74, commandIdentifier=0, statusCode=FAILURE]]
2023-10-06 10:04:41.499 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=5, outstandingQueues=1, sleepy=1/3
2023-10-06 10:04:41.500 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node CF69 did not translate to command
2023-10-06 10:04:41.656 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=9B44, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0C], messageTag=A4, status=EMBER_DELIVERY_FAILED, messageContents=]
2023-10-06 10:04:41.657 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=A4 state=RX_NAK
2023-10-06 10:04:41.658 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=A4, state=RX_NAK, outstanding=5
2023-10-06 10:04:41.660 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B0029174370 queueTime=9640, state=FAILED, sendCnt=2, command=BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]]
2023-10-06 10:04:41.661 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
2023-10-06 10:04:41.662 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B0029174370: transactionComplete, state=FAILED, outstanding=0
2023-10-06 10:04:41.662 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B0029174370: transactionComplete exceeded max retries 2
2023-10-06 10:04:41.664 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=00124B0029174370 queueTime=9644, state=CANCELLED, sendCnt=2, command=BindRequest [0000/0 -> 9B44/0, cluster=0021, TID=A4, bindingTableEntry=BindingTable [srcAddr=00124B0029174370/1, addrMode=DEVICE, dstAddr=DC8E95FFFE2579C4/1, clusterId=0001]]]
2023-10-06 10:04:41.664 [ERROR] [verter.ZigBeeConverterBatteryPercent] - 00124B0029174370: Error 0xffff setting server binding