I have restored my openhab configuration from a backup. The system was originally running openhabian but I have since moved to running it as a docker container. For the most part things have restored to this new configuration. Zwave bindings are (apparently) working fine. However, zigbee is problematic. My devices are all showing as “online” but I don’t think there is any actual radio communication with them.
Some background:
The SD card in the raspberry pi went bad, hence the recovery from backup.
The device was completely offline for at least a week. Not sure just the time disconnected from the controller has somehow put these zigbee devices in a bad state.
I have not tried resetting the devices and rejoining. I’m hoping to avoid that if possible.
Logs show spew like:
2020-06-20 22:04:30.899 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - E20DB9FFFE0ADEFD: Command for channel zigbee:device:2523bd5d:e20db9fffe0adefd:E20DB9FFFE0ADEFD_10_dimmer --> OFF [OnOffType]
2020-06-20 22:04:30.914 [DEBUG] [transaction.ZigBeeTransactionManager] - EA97/10: Sending ZigBeeTransaction [ieeeAddress=E20DB9FFFE0AAF42 queueTime=74449, state=WAITING, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> EA97/10, cluster=0006, TID=16]]
2020-06-20 22:04:30.918 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2020-06-20 22:04:30.924 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> EA97/10, cluster=0006, TID=16]
2020-06-20 22:04:30.931 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=22, commandId=1]
2020-06-20 22:04:30.951 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=EA97/10, profile=0104, cluster=0006, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=B1, rssi=–, lqi=–, payload=01 16 01]
2020-06-20 22:04:30.963 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - E20DB9FFFE0ADEFD: Added transaction to queue, len=3, transaction=ZigBeeTransaction [ieeeAddress=E20DB9FFFE0ADEFD queueTime=0, state=WAITING, sendCnt=0, command=OffCommand [On/Off: 0000/0 -> 82A7/10, cluster=0006, TID=–]]
2020-06-20 22:04:30.975 [DEBUG] [transaction.ZigBeeTransactionManager] - 82A7/10: Sending ZigBeeTransaction [ieeeAddress=E20DB9FFFE0ADEFD queueTime=62770, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Level Control: 0000/0 -> 82A7/10, cluster=0008, TID=19, identifiers=[0]]]
2020-06-20 22:04:30.980 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
2020-06-20 22:04:30.984 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=46]
2020-06-20 22:04:30.986 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Level Control: 0000/0 -> 82A7/10, cluster=0008, TID=19, identifiers=[0]]
2020-06-20 22:04:30.990 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=25, commandId=0]
2020-06-20 22:04:30.996 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=82A7/10, profile=0104, cluster=0008, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=B2, rssi=–, lqi=–, payload=00 19 00 00 00]
2020-06-20 22:04:31.003 [DEBUG] [transaction.ZigBeeTransactionManager] - EA97/10: Sending ZigBeeTransaction [ieeeAddress=E20DB9FFFE0AAF42 queueTime=74598, state=WAITING, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> EA97/10, cluster=0006, TID=14]]
2020-06-20 22:04:31.006 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 3 outstanding
2020-06-20 22:04:31.011 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> EA97/10, cluster=0006, TID=14]
2020-06-20 22:04:31.014 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=20, commandId=1]
2020-06-20 22:04:31.015 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=47]
2020-06-20 22:04:31.019 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=EA97/10, profile=0104, cluster=0006, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=B3, rssi=–, lqi=–, payload=01 14 01]
2020-06-20 22:04:31.025 [DEBUG] [transaction.ZigBeeTransactionManager] - 82A7/10: Sending ZigBeeTransaction [ieeeAddress=E20DB9FFFE0ADEFD queueTime=74509, state=WAITING, sendCnt=1, command=OnCommand [On/Off: 0000/0 -> 82A7/10, cluster=0006, TID=18]]
2020-06-20 22:04:31.028 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 4 outstanding
2020-06-20 22:04:31.033 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OnCommand [On/Off: 0000/0 -> 82A7/10, cluster=0006, TID=18]
2020-06-20 22:04:31.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=24, commandId=1]
2020-06-20 22:04:31.038 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=48]
2020-06-20 22:04:31.043 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=82A7/10, profile=0104, cluster=0006, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=B4, rssi=–, lqi=–, payload=01 18 01]
2020-06-20 22:04:31.060 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=49]
2020-06-20 22:04:35.679 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=60055, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=10, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=46], messageTag=16, status=EMBER_DELIVERY_FAILED, messageContents=]
2020-06-20 22:04:35.684 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=16 state=RX_NAK
2020-06-20 22:04:35.690 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=16, state=RX_NAK, outstanding=4
2020-06-20 22:04:35.717 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=33447, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=10, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=47], messageTag=19, status=EMBER_DELIVERY_FAILED, messageContents=]
2020-06-20 22:04:35.721 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=E20DB9FFFE0AAF42 queueTime=79238, state=FAILED, sendCnt=2, command=OnCommand [On/Off: 0000/0 -> EA97/10, cluster=0006, TID=16]]
2020-06-20 22:04:35.723 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=19 state=RX_NAK
2020-06-20 22:04:35.726 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
2020-06-20 22:04:35.728 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=19, state=RX_NAK, outstanding=3
2020-06-20 22:04:35.730 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - E20DB9FFFE0AAF42: transactionComplete FAILED 1
2020-06-20 22:04:35.735 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - E20DB9FFFE0AAF42: transactionComplete exceeded retries 2