I upgraded my system the other day to openHAB 2.5.10-1 (Release Build). When doing so I lost all my Zigbee devices. After rejoining them one by one I went to bed happy that it was fixed rather painlessly. Now today they are all gone again! They are marked as “Offline - Gone” and when I try to rejoining them I notice that I get a few Null-pointers in the log. I updated the binding using the script, but the problem persists.
2020-11-17 22:47:20.252 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,78EC,E0892586FAC96AA2
2020-11-17 22:47:20.254 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2020-11-17 22:47:20.255 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30956, epanId=E0892586FAC96AA2, status=SUCCESS]
2020-11-17 22:47:21.047 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NEWNODE:732F,14B457FFFE2D9641,0000
2020-11-17 22:47:21.050 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceJoinedNetworkEvent [networkAddress=29487, ieeeAddress=14B457FFFE2D9641, parentAddress=0]
2020-11-17 22:47:21.053 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 14B457FFFE2D9641: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 732F.
2020-11-17 22:47:21.056 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 14B457FFFE2D9641: Device status updated. NWK=732F
2020-11-17 22:47:21.058 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 14B457FFFE2D9641: NWK Discovery add node 732F
2020-11-17 22:47:21.062 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 14B457FFFE2D9641: Polling initialised at 1803283ms
2020-11-17 22:47:21.062 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 14B457FFFE2D9641: Node state updated from UNKNOWN to ONLINE
2020-11-17 22:47:21.068 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 14B457FFFE2D9641: Updating node NWK=732F
2020-11-17 22:47:21.072 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 14B457FFFE2D9641: Data store: Deferring write for 250ms.
2020-11-17 22:47:21.075 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - 14B457FFFE2D9641: DISCOVERY Extension: Adding discoverer for node
2020-11-17 22:47:21.079 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14B457FFFE2D9641: Node SVC Discovery: created discoverer
2020-11-17 22:47:21.084 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 14B457FFFE2D9641: Node updated - ZigBeeNode [state=ONLINE, IEEE=14B457FFFE2D9641, NWK=732F, endpoints=[]]
2020-11-17 22:47:21.087 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14B457FFFE2D9641: Node SVC Discovery: start discovery
2020-11-17 22:47:21.087 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 14B457FFFE2D9641: Starting ZigBee device discovery
2020-11-17 22:47:21.091 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 14B457FFFE2D9641: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:0000000C
2020-11-17 22:47:21.092 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14B457FFFE2D9641: Node SVC Discovery: starting new tasks [NWK_ADDRESS, NODE_DESCRIPTOR, ACTIVE_ENDPOINTS, POWER_DESCRIPTOR]
2020-11-17 22:47:21.099 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14B457FFFE2D9641: Node SVC Discovery: scheduled [NWK_ADDRESS, NODE_DESCRIPTOR, ACTIVE_ENDPOINTS, POWER_DESCRIPTOR]
2020-11-17 22:47:21.102 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-68
java.lang.NullPointerException: null
at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeAdded(ZigBeeThingHandler.java:815) ~[?:?]
at com.zsmartsystems.zigbee.ZigBeeNetworkManager$12.run(ZigBeeNetworkManager.java:1653) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_265]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_265]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
2020-11-17 22:47:21.130 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 14B457FFFE2D9641: Node discovery not complete
2020-11-17 22:47:21.221 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2020-11-17 22:47:21.223 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2020-11-17 22:47:21.225 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2020-11-17 22:47:21.255 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,78EC,E0892586FAC96AA2
2020-11-17 22:47:21.256 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2020-11-17 22:47:21.257 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30956, epanId=E0892586FAC96AA2, status=SUCCESS]
2020-11-17 22:47:21.325 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 14B457FFFE2D9641: Data store: Writing node.
2020-11-17 22:47:21.332 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 14B457FFFE2D9641: ZigBee saving network state complete.
2020-11-17 22:47:22.227 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2020-11-17 22:47:22.230 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2020-11-17 22:47:22.234 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2020-11-17 22:47:22.266 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,78EC,E0892586FAC96AA2
2020-11-17 22:47:22.268 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2020-11-17 22:47:22.270 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30956, epanId=E0892586FAC96AA2, status=SUCCESS]
2020-11-17 22:47:22.358 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14B457FFFE2D9641: Node SVC Discovery: running NWK_ADDRESS
2020-11-17 22:47:22.360 [DEBUG] [transaction.ZigBeeTransactionManager] - 14B457FFFE2D9641: Creating new Transaction Queue
2020-11-17 22:47:22.363 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 14B457FFFE2D9641: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
2020-11-17 22:47:22.367 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 14B457FFFE2D9641: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=14B457FFFE2D9641 queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 732F/0, cluster=0000, TID=--, ieeeAddr=14B457FFFE2D9641, requestType=0, startIndex=0]]
2020-11-17 22:47:22.369 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2020-11-17 22:47:22.372 [DEBUG] [transaction.ZigBeeTransactionManager] - 732F/0: Sending ZigBeeTransaction [ieeeAddress=14B457FFFE2D9641 queueTime=6, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> 732F/0, cluster=0000, TID=8B, ieeeAddr=14B457FFFE2D9641, requestType=0, startIndex=0]]
2020-11-17 22:47:22.374 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2020-11-17 22:47:22.377 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> 732F/0, cluster=0000, TID=8B, ieeeAddr=14B457FFFE2D9641, requestType=0, startIndex=0]
2020-11-17 22:47:22.381 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=732F/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=9D, rssi=--, lqi=--, payload=8B 41 96 2D FE FF 57 B4 14 00 00]
2020-11-17 22:47:22.383 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=29487, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=8B 41 96 2D FE FF 57 B4 14 00 00, messageId=null]
2020-11-17 22:47:22.386 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2020-11-17 22:47:22.389 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=29487, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=8B 41 96 2D FE FF 57 B4 14 00 00, messageId=null]
2020-11-17 22:47:22.395 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:0B,732F,00,00,0000,0000
A-þÿW´
Any thoughts why my devices are lost and what that nullpointer is about?