Page 1 of 1

[Zigate] Unreachable device reported every two days or so

Posted: Sunday 23 February 2020 9:15
by kassoum
Hi Guys !
I didn't do much modifications since my last small issue https://www.domoticz.com/forum/viewtopi ... 68&t=31312.
However every two days or so, i wake up and see that none of my automatic schedules were executed.

I don't have the logs from the first times but it was always similar to this :

Code: Select all

2020-02-20 23:39:04.740  (Zigate) pingZigate - WARNING: Ping sent but no response yet from Zigate. Status: Sent  - Ping: 45 sec
2020-02-20 23:39:04.742  Error: (Zigate) No transport, write directive to 'Zigate' ignored.
2020-02-20 23:39:09.760  (Zigate) pingZigate - WARNING: Ping sent but no response yet from Zigate. Status: Sent  - Ping: 50 sec
2020-02-20 23:39:14.729  (Zigate) pingZigate - WARNING: Ping sent but no response yet from Zigate. Status: Sent  - Ping: 55 sec
2020-02-20 23:39:19.748  (Zigate) pingZigate - WARNING: Ping sent but no response yet from Zigate. Status: Sent  - Ping: 60 sec
2020-02-20 23:39:19.750  Error: (Zigate) pingZigate - no Heartbeat with Zigate, try to reConnect
2020-02-20 23:39:19.751  (Zigate) Transport.reConn: Name: 'Zigate', Transport: 'TCP/IP', Protocol: 'None ', Address: '8x.4x.17x.18x', Port: '9999', Baud: -1, Connected: False, Parent: 'None'
2020-02-20 23:39:19.751  (Zigate) Lost connection, reConn Transport.reConn: Name: 'Zigate', Transport: 'TCP/IP', Protocol: 'None ', Address: '8x.4x.17x.18x', Port: '9999', Baud: -1, Connected: False, Parent: 'None'
2020-02-20 23:39:19.752  Status: (Zigate) Connection Name: Zigate, Transport: TCP/IP, Address: None:9999
[...]
2020-02-20 23:40:24.892  Error: (Zigate) onMessage : Frame CRC is bad, computed = 112 received = 242
2020-02-20 23:40:39.898  Error: (Zigate) onMessage : Frame CRC is bad, computed = 119 received = 240
2020-02-20 23:40:54.947  Error: (Zigate) onMessage : Frame size is bad, computed = 61 received = 69
[..]
2020-02-21 00:30:41.561  Error: (Zigate) pingZigate - unknown status : None
2020-02-21 00:30:46.531  Error: (Zigate) pingZigate - unknown status : None
2020-02-21 00:30:51.550  Error: (Zigate) pingZigate - unknown status : None
[...]

Code: Select all

Last unknown status : None 
displays every seconds (or even multiple times a second) until I restart domoticz.
If I tcpdump during the issue I don't see any communication towards the zigate.
restarting domoticz solves the issue.

This morning the logs were completely different
These are the logs I have :

Code: Select all

2020-02-23 06:21:31.631  Status: (Zigate) Device Annoucement ShortAddr: 13ec, IEEE: 00158d0002a71956 
2020-02-23 06:21:31.632  (Zigate) Decode004d - rebind clusters for 13ec
2020-02-23 06:29:01.974  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.5;89.0;3;1008.4;3
2020-02-23 06:29:01.976  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum-00158d0001ab445f-01) 0:10.5;89.0;3
2020-02-23 06:29:01.978  (Zigate) UpdateDevice - (Balcon-Humidité) 89.0:3
2020-02-23 06:29:02.087  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.5;89.0;3;1008.0;3
2020-02-23 06:29:02.093  (Zigate) UpdateDevice - (Balcon-Baromètre) 0:1008.0;3
2020-02-23 06:29:02.096  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.5;89.0;3;1008.4;3
2020-02-23 06:29:02.097  (Zigate) UpdateDevice - (Balcon-Baromètre) 0:1008.4;3
2020-02-23 06:35:47.645  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.6;89.0;3;1008.4;3
2020-02-23 06:35:47.647  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum-00158d0001ab445f-01) 0:10.6;89.0;3
2020-02-23 06:35:47.648  (Zigate) UpdateDevice - (Balcon-Température) 10.6:10.6
2020-02-23 06:35:47.801  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.6;89.0;3;1008.0;3
2020-02-23 06:35:47.803  (Zigate) UpdateDevice - (Balcon-Baromètre) 0:1008.0;3
2020-02-23 06:35:47.805  (Zigate) UpdateDevice - (Zigate - lumi.weather_Temp+Hum+Baro-00158d0001ab445f-01) 0:10.6;89.0;3;1008.1;3
2020-02-23 06:35:47.806  (Zigate) UpdateDevice - (Balcon-Baromètre) 0:1008.1;3
2020-02-23 06:44:25.270  Error: PluginSystem: Exception processing message.
2020-02-23 06:45:33.336  Error: Zigate hardware (2) thread seems to have ended unexpectedly
2020-02-23 06:45:47.341  Error: Zigate hardware (2) thread seems to have ended unexpectedly
2020-02-23 06:46:01.346  Error: Zigate hardware (2) thread seems to have ended unexpectedly
this last error is repeated every 14seconds until I wake up in the morning and restart domoticz
This time restarting domoticz solved the issue as well but it took a very long time.
I did a ram snapshot of my VM with the issue if needed.
I was not able to find the error in the forum existing thread so I allow myself to open this thread.

I am using last stable domoticz release 4.10717 2019-05-09 and I can see those kind of messages.

Code: Select all

Error: (Zigate) ATTENTION: the WebServer part is not supported with this version of Domoticz. Please upgrade to a version greater than 4.10901
As for zigate I am on the stable branch as well.

Code: Select all

root@domo:/home/domoticz/domoticz/plugins/Domoticz-Zigate# git branch
* stable
As a workaround i could just monitor the logs and when recording only errors i can restart domoticz for me to avoid any issue when i am not home.
Thanks in advance for your advices :)

Re: [Zigate] Unreachable device reported every two days or so

Posted: Sunday 23 February 2020 9:27
by pipiche
As displayed in the logs, at a point of time the plugin is not able to reach the Zigate via the connection channel (wifi in your case).

For your second case, having only the last part of the log doesn't help to find the root cause. But most-likely this could be link to no connection between plugin and zigate.