Sensors stop updating state after a few hours

jalme commented 5 years ago

Hi,

HA version 0.83.3 on Linux + Docker Zigate Wifi Two door sensor from Xiaomi

After a couple of hours every day the sensors stop reporting state.

It takes a reboot of HA to put the system back in working order until it hangs again.

Logs bellow, first is the log of the duration of the hang (I've marked the point here it stopped receiving updates from zigate). Second log is the reboot (this time I had to reboot HA 2 times before it started to work), second reboot is also marked.

First log: 2018-12-05 21:56:09 DEBUG (SyncWorker_4) [zigate] Check Need refresh Device 00158d0002536f0b (5cdd) lumi.sensor_magnet.aq2 2018-12-05 21:56:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_DEVICE_UPDATED 2018-12-05 21:56:09 DEBUG (SyncWorker_4) [zigate] Auto saving /config/zigate.json 2018-12-05 21:56:09 DEBUG (SyncWorker_4) [zigate] Saving persistent file 2018-12-05 21:56:09 DEBUG (SyncWorker_4) [zigate] File already existing, make a backup before 2018-12-05 22:01:09 DEBUG (Thread-3) [zigate] Auto saving /config/zigate.json 2018-12-05 22:01:09 DEBUG (Thread-3) [zigate] Saving persistent file 2018-12-05 22:01:09 DEBUG (Thread-3) [zigate] File already existing, make a backup before 2018-12-05 22:06:09 DEBUG (Thread-4) [zigate] Auto saving /config/zigate.json 2018-12-05 22:06:09 DEBUG (Thread-4) [zigate] Saving persistent file 2018-12-05 22:06:09 DEBUG (Thread-4) [zigate] File already existing, make a backup before 2018-12-05 22:07:10 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-05 22:07:10 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'eebaf9010000ff010042001d0121db0b0328160421a81305211d00062405000000000a210000641000' 2018-12-05 22:07:10 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:238, addr:baf9, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121db0b0328160421a81305211d00062405000000000a210000641000, rssi:84 2018-12-05 22:07:10 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-05 22:07:10 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-05 22:11:09 DEBUG (Thread-5) [zigate] Auto saving /config/zigate.json 2018-12-05 22:11:09 DEBUG (Thread-5) [zigate] Saving persistent file 2018-12-05 22:11:09 DEBUG (Thread-5) [zigate] File already existing, make a backup before 2018-12-05 22:16:09 DEBUG (Thread-6) [zigate] Auto saving /config/zigate.json 2018-12-05 22:16:09 DEBUG (Thread-6) [zigate] Saving persistent file 2018-12-05 22:16:09 DEBUG (Thread-6) [zigate] File already existing, make a backup before 2018-12-05 22:21:09 DEBUG (Thread-7) [zigate] Auto saving /config/zigate.json 2018-12-05 22:21:09 DEBUG (Thread-7) [zigate] Saving persistent file 2018-12-05 22:21:09 DEBUG (Thread-7) [zigate] File already existing, make a backup before 2018-12-05 22:26:09 DEBUG (Thread-8) [zigate] Auto saving /config/zigate.json 2018-12-05 22:26:09 DEBUG (Thread-8) [zigate] Saving persistent file 2018-12-05 22:26:09 DEBUG (Thread-8) [zigate] File already existing, make a backup before 2018-12-05 22:31:09 DEBUG (Thread-9) [zigate] Auto saving /config/zigate.json 2018-12-05 22:31:09 DEBUG (Thread-9) [zigate] Saving persistent file 2018-12-05 22:31:09 DEBUG (Thread-9) [zigate] File already existing, make a backup before 2018-12-05 22:36:09 DEBUG (Thread-10) [zigate] Auto saving /config/zigate.json 2018-12-05 22:36:09 DEBUG (Thread-10) [zigate] Saving persistent file 2018-12-05 22:36:09 DEBUG (Thread-10) [zigate] File already existing, make a backup before 2018-12-05 22:38:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-05 22:38:48 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'df5cdd010000ff010042001d0121ef0b0328130421a81305210800062401000000000a210000641000' 2018-12-05 22:38:48 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:223, addr:5cdd, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121ef0b0328130421a81305210800062401000000000a210000641000, rssi:117 2018-12-05 22:38:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-05 22:38:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-05 22:41:09 DEBUG (Thread-11) [zigate] Auto saving /config/zigate.json 2018-12-05 22:41:09 DEBUG (Thread-11) [zigate] Saving persistent file 2018-12-05 22:41:09 DEBUG (Thread-11) [zigate] File already existing, make a backup before 2018-12-05 22:46:09 DEBUG (Thread-12) [zigate] Auto saving /config/zigate.json 2018-12-05 22:46:09 DEBUG (Thread-12) [zigate] Saving persistent file 2018-12-05 22:46:09 DEBUG (Thread-12) [zigate] File already existing, make a backup before 2018-12-05 22:51:09 DEBUG (Thread-13) [zigate] Auto saving /config/zigate.json 2018-12-05 22:51:09 DEBUG (Thread-13) [zigate] Saving persistent file 2018-12-05 22:51:09 DEBUG (Thread-13) [zigate] File already existing, make a backup before 2018-12-05 22:55:25 ERROR (MainThread) [homeassistant.components.updater] Got unexpected response: None 2018-12-05 22:56:09 DEBUG (Thread-14) [zigate] Auto saving /config/zigate.json 2018-12-05 22:56:09 DEBUG (Thread-14) [zigate] Saving persistent file 2018-12-05 22:56:09 DEBUG (Thread-14) [zigate] File already existing, make a backup before 2018-12-05 22:57:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-05 22:57:48 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'efbaf9010000ff010042001d0121db0b0328150421a81305211d00062401000000000a210000641000' 2018-12-05 22:57:48 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:239, addr:baf9, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121db0b0328150421a81305211d00062401000000000a210000641000, rssi:84 2018-12-05 22:57:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-05 22:57:48 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-05 23:01:09 DEBUG (Thread-15) [zigate] Auto saving /config/zigate.json 2018-12-05 23:01:09 DEBUG (Thread-15) [zigate] Saving persistent file 2018-12-05 23:01:09 DEBUG (Thread-15) [zigate] File already existing, make a backup before 2018-12-05 23:06:09 DEBUG (Thread-16) [zigate] Auto saving /config/zigate.json 2018-12-05 23:06:09 DEBUG (Thread-16) [zigate] Saving persistent file 2018-12-05 23:06:09 DEBUG (Thread-16) [zigate] File already existing, make a backup before 2018-12-05 23:08:39 ERROR (MainThread) [homeassistant.core] Error doing job: SSL handshake failed Traceback (most recent call last): File "uvloop/handles/stream.pyx", line 609, in uvloop.loop.UVStream._on_eof File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-05 23:08:39 ERROR (MainThread) [homeassistant.core] Error doing job: SSL error errno:1 reason: WRONG_VERSION_NUMBER Traceback (most recent call last): File "uvloop/sslproto.pyx", line 504, in uvloop.loop.SSLProtocol.data_received File "uvloop/sslproto.pyx", line 204, in uvloop.loop._SSLPipe.feed_ssldata File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-05 23:11:09 DEBUG (Thread-17) [zigate] Auto saving /config/zigate.json 2018-12-05 23:11:09 DEBUG (Thread-17) [zigate] Saving persistent file 2018-12-05 23:11:09 DEBUG (Thread-17) [zigate] File already existing, make a backup before 2018-12-05 23:16:09 DEBUG (Thread-18) [zigate] Auto saving /config/zigate.json 2018-12-05 23:16:09 DEBUG (Thread-18) [zigate] Saving persistent file 2018-12-05 23:16:09 DEBUG (Thread-18) [zigate] File already existing, make a backup before 2018-12-05 23:21:09 DEBUG (Thread-19) [zigate] Auto saving /config/zigate.json 2018-12-05 23:21:09 DEBUG (Thread-19) [zigate] Saving persistent file 2018-12-05 23:21:09 DEBUG (Thread-19) [zigate] File already existing, make a backup before 2018-12-05 23:26:09 DEBUG (Thread-20) [zigate] Auto saving /config/zigate.json 2018-12-05 23:26:09 DEBUG (Thread-20) [zigate] Saving persistent file 2018-12-05 23:26:09 DEBUG (Thread-20) [zigate] File already existing, make a backup before 2018-12-05 23:31:09 DEBUG (Thread-21) [zigate] Auto saving /config/zigate.json 2018-12-05 23:31:09 DEBUG (Thread-21) [zigate] Saving persistent file 2018-12-05 23:31:09 DEBUG (Thread-21) [zigate] File already existing, make a backup before 2018-12-05 23:36:09 DEBUG (Thread-22) [zigate] Auto saving /config/zigate.json 2018-12-05 23:36:09 DEBUG (Thread-22) [zigate] Saving persistent file 2018-12-05 23:36:09 DEBUG (Thread-22) [zigate] File already existing, make a backup before 2018-12-05 23:37:07 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-05 23:37:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'e05cdd010000ff010042001d0121ef0b0328130421a81305210800062401000000000a210000641000' 2018-12-05 23:37:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:224, addr:5cdd, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121ef0b0328130421a81305210800062401000000000a210000641000, rssi:117 2018-12-05 23:37:07 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-05 23:37:07 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-05 23:41:09 DEBUG (Thread-23) [zigate] Auto saving /config/zigate.json 2018-12-05 23:41:09 DEBUG (Thread-23) [zigate] Saving persistent file 2018-12-05 23:41:09 DEBUG (Thread-23) [zigate] File already existing, make a backup before 2018-12-05 23:41:26 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-05 23:41:26 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'f0baf901000600000010000101' 2018-12-05 23:41:26 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:240, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:True, rssi:84 2018-12-05 23:41:26 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-05 23:41:26 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED <<<<<<------Door sensor stays open from this point onwards 2018-12-05 23:46:09 DEBUG (Thread-24) [zigate] Auto saving /config/zigate.json 2018-12-05 23:46:09 DEBUG (Thread-24) [zigate] Saving persistent file 2018-12-05 23:46:09 DEBUG (Thread-24) [zigate] File already existing, make a backup before 2018-12-05 23:51:09 DEBUG (Thread-25) [zigate] Auto saving /config/zigate.json 2018-12-05 23:51:09 DEBUG (Thread-25) [zigate] Saving persistent file 2018-12-05 23:51:09 DEBUG (Thread-25) [zigate] File already existing, make a backup before 2018-12-05 23:56:09 DEBUG (Thread-26) [zigate] Auto saving /config/zigate.json 2018-12-05 23:56:09 DEBUG (Thread-26) [zigate] Saving persistent file 2018-12-05 23:56:09 DEBUG (Thread-26) [zigate] File already existing, make a backup before 2018-12-06 00:00:00 DEBUG (SyncWorker_1) [zigate] REQUEST : 0x0015 2018-12-06 00:00:00 DEBUG (SyncWorker_1) [zigate] Msg to send b'0015000015' 2018-12-06 00:00:00 DEBUG (SyncWorker_1) [zigate] Encoded Msg to send b'01021015021002101503' 2018-12-06 00:00:00 DEBUG (SyncWorker_1) [zigate] Waiting for status message for command 0x0015 2018-12-06 00:00:03 WARNING (SyncWorker_1) [zigate] No response after command 0x0015 2018-12-06 00:01:09 DEBUG (Thread-27) [zigate] Auto saving /config/zigate.json 2018-12-06 00:01:09 DEBUG (Thread-27) [zigate] Saving persistent file 2018-12-06 00:01:09 DEBUG (Thread-27) [zigate] File already existing, make a backup before 2018-12-06 00:06:09 DEBUG (Thread-28) [zigate] Auto saving /config/zigate.json 2018-12-06 00:06:09 DEBUG (Thread-28) [zigate] Saving persistent file 2018-12-06 00:06:09 DEBUG (Thread-28) [zigate] File already existing, make a backup before 2018-12-06 00:11:09 DEBUG (Thread-29) [zigate] Auto saving /config/zigate.json 2018-12-06 00:11:09 DEBUG (Thread-29) [zigate] Saving persistent file 2018-12-06 00:11:09 DEBUG (Thread-29) [zigate] File already existing, make a backup before 2018-12-06 00:16:09 DEBUG (Thread-30) [zigate] Auto saving /config/zigate.json 2018-12-06 00:16:09 DEBUG (Thread-30) [zigate] Saving persistent file 2018-12-06 00:16:09 DEBUG (Thread-30) [zigate] File already existing, make a backup before 2018-12-06 00:21:09 DEBUG (Thread-31) [zigate] Auto saving /config/zigate.json 2018-12-06 00:21:09 DEBUG (Thread-31) [zigate] Saving persistent file 2018-12-06 00:21:09 DEBUG (Thread-31) [zigate] File already existing, make a backup before 2018-12-06 00:26:09 DEBUG (Thread-32) [zigate] Auto saving /config/zigate.json 2018-12-06 00:26:09 DEBUG (Thread-32) [zigate] Saving persistent file 2018-12-06 00:26:09 DEBUG (Thread-32) [zigate] File already existing, make a backup before 2018-12-06 00:31:09 DEBUG (Thread-33) [zigate] Auto saving /config/zigate.json 2018-12-06 00:31:09 DEBUG (Thread-33) [zigate] Saving persistent file 2018-12-06 00:31:09 DEBUG (Thread-33) [zigate] File already existing, make a backup before 2018-12-06 00:36:09 DEBUG (Thread-34) [zigate] Auto saving /config/zigate.json 2018-12-06 00:36:10 DEBUG (Thread-34) [zigate] Saving persistent file 2018-12-06 00:36:10 DEBUG (Thread-34) [zigate] File already existing, make a backup before 2018-12-06 00:41:10 DEBUG (Thread-35) [zigate] Auto saving /config/zigate.json 2018-12-06 00:41:10 DEBUG (Thread-35) [zigate] Saving persistent file 2018-12-06 00:41:10 DEBUG (Thread-35) [zigate] File already existing, make a backup before 2018-12-06 00:46:10 DEBUG (Thread-36) [zigate] Auto saving /config/zigate.json 2018-12-06 00:46:10 DEBUG (Thread-36) [zigate] Saving persistent file 2018-12-06 00:46:10 DEBUG (Thread-36) [zigate] File already existing, make a backup before 2018-12-06 00:46:51 ERROR (MainThread) [homeassistant.core] Error doing job: SSL handshake failed Traceback (most recent call last): File "uvloop/handles/stream.pyx", line 609, in uvloop.loop.UVStream._on_eof File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-06 00:46:51 ERROR (MainThread) [homeassistant.core] Error doing job: SSL error errno:1 reason: WRONG_VERSION_NUMBER Traceback (most recent call last): File "uvloop/sslproto.pyx", line 504, in uvloop.loop.SSLProtocol.data_received File "uvloop/sslproto.pyx", line 204, in uvloop.loop._SSLPipe.feed_ssldata File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-06 00:51:10 DEBUG (Thread-37) [zigate] Auto saving /config/zigate.json 2018-12-06 00:51:10 DEBUG (Thread-37) [zigate] Saving persistent file 2018-12-06 00:51:10 DEBUG (Thread-37) [zigate] File already existing, make a backup before 2018-12-06 00:56:10 DEBUG (Thread-38) [zigate] Auto saving /config/zigate.json 2018-12-06 00:56:10 DEBUG (Thread-38) [zigate] Saving persistent file 2018-12-06 00:56:10 DEBUG (Thread-38) [zigate] File already existing, make a backup before 2018-12-06 01:01:10 DEBUG (Thread-39) [zigate] Auto saving /config/zigate.json 2018-12-06 01:01:10 DEBUG (Thread-39) [zigate] Saving persistent file 2018-12-06 01:01:10 DEBUG (Thread-39) [zigate] File already existing, make a backup before 2018-12-06 01:06:10 DEBUG (Thread-40) [zigate] Auto saving /config/zigate.json 2018-12-06 01:06:10 DEBUG (Thread-40) [zigate] Saving persistent file 2018-12-06 01:06:10 DEBUG (Thread-40) [zigate] File already existing, make a backup before 2018-12-06 01:11:10 DEBUG (Thread-41) [zigate] Auto saving /config/zigate.json 2018-12-06 01:11:10 DEBUG (Thread-41) [zigate] Saving persistent file 2018-12-06 01:11:10 DEBUG (Thread-41) [zigate] File already existing, make a backup before 2018-12-06 01:16:10 DEBUG (Thread-42) [zigate] Auto saving /config/zigate.json 2018-12-06 01:16:10 DEBUG (Thread-42) [zigate] Saving persistent file 2018-12-06 01:16:10 DEBUG (Thread-42) [zigate] File already existing, make a backup before 2018-12-06 01:21:10 DEBUG (Thread-43) [zigate] Auto saving /config/zigate.json 2018-12-06 01:21:10 DEBUG (Thread-43) [zigate] Saving persistent file 2018-12-06 01:21:10 DEBUG (Thread-43) [zigate] File already existing, make a backup before 2018-12-06 01:26:10 DEBUG (Thread-44) [zigate] Auto saving /config/zigate.json 2018-12-06 01:26:10 DEBUG (Thread-44) [zigate] Saving persistent file 2018-12-06 01:26:10 DEBUG (Thread-44) [zigate] File already existing, make a backup before 2018-12-06 01:31:10 DEBUG (Thread-45) [zigate] Auto saving /config/zigate.json 2018-12-06 01:31:10 DEBUG (Thread-45) [zigate] Saving persistent file 2018-12-06 01:31:10 DEBUG (Thread-45) [zigate] File already existing, make a backup before 2018-12-06 01:36:10 DEBUG (Thread-46) [zigate] Auto saving /config/zigate.json 2018-12-06 01:36:10 DEBUG (Thread-46) [zigate] Saving persistent file 2018-12-06 01:36:10 DEBUG (Thread-46) [zigate] File already existing, make a backup before 2018-12-06 01:41:10 DEBUG (Thread-47) [zigate] Auto saving /config/zigate.json 2018-12-06 01:41:10 DEBUG (Thread-47) [zigate] Saving persistent file 2018-12-06 01:41:10 DEBUG (Thread-47) [zigate] File already existing, make a backup before 2018-12-06 01:46:10 DEBUG (Thread-48) [zigate] Auto saving /config/zigate.json 2018-12-06 01:46:10 DEBUG (Thread-48) [zigate] Saving persistent file 2018-12-06 01:46:10 DEBUG (Thread-48) [zigate] File already existing, make a backup before 2018-12-06 01:51:10 DEBUG (Thread-49) [zigate] Auto saving /config/zigate.json 2018-12-06 01:51:10 DEBUG (Thread-49) [zigate] Saving persistent file 2018-12-06 01:51:10 DEBUG (Thread-49) [zigate] File already existing, make a backup before 2018-12-06 01:56:10 DEBUG (Thread-50) [zigate] Auto saving /config/zigate.json 2018-12-06 01:56:10 DEBUG (Thread-50) [zigate] Saving persistent file 2018-12-06 01:56:10 DEBUG (Thread-50) [zigate] File already existing, make a backup before 2018-12-06 02:01:10 DEBUG (Thread-51) [zigate] Auto saving /config/zigate.json 2018-12-06 02:01:10 DEBUG (Thread-51) [zigate] Saving persistent file 2018-12-06 02:01:10 DEBUG (Thread-51) [zigate] File already existing, make a backup before 2018-12-06 02:06:10 DEBUG (Thread-52) [zigate] Auto saving /config/zigate.json 2018-12-06 02:06:10 DEBUG (Thread-52) [zigate] Saving persistent file 2018-12-06 02:06:10 DEBUG (Thread-52) [zigate] File already existing, make a backup before 2018-12-06 02:11:10 DEBUG (Thread-53) [zigate] Auto saving /config/zigate.json 2018-12-06 02:11:10 DEBUG (Thread-53) [zigate] Saving persistent file 2018-12-06 02:11:10 DEBUG (Thread-53) [zigate] File already existing, make a backup before 2018-12-06 02:16:10 DEBUG (Thread-54) [zigate] Auto saving /config/zigate.json 2018-12-06 02:16:10 DEBUG (Thread-54) [zigate] Saving persistent file 2018-12-06 02:16:10 DEBUG (Thread-54) [zigate] File already existing, make a backup before 2018-12-06 02:21:10 DEBUG (Thread-55) [zigate] Auto saving /config/zigate.json 2018-12-06 02:21:10 DEBUG (Thread-55) [zigate] Saving persistent file 2018-12-06 02:21:10 DEBUG (Thread-55) [zigate] File already existing, make a backup before 2018-12-06 02:26:10 DEBUG (Thread-56) [zigate] Auto saving /config/zigate.json 2018-12-06 02:26:10 DEBUG (Thread-56) [zigate] Saving persistent file 2018-12-06 02:26:10 DEBUG (Thread-56) [zigate] File already existing, make a backup before 2018-12-06 02:31:10 DEBUG (Thread-57) [zigate] Auto saving /config/zigate.json 2018-12-06 02:31:10 DEBUG (Thread-57) [zigate] Saving persistent file 2018-12-06 02:31:10 DEBUG (Thread-57) [zigate] File already existing, make a backup before 2018-12-06 02:36:10 DEBUG (Thread-58) [zigate] Auto saving /config/zigate.json 2018-12-06 02:36:10 DEBUG (Thread-58) [zigate] Saving persistent file 2018-12-06 02:36:10 DEBUG (Thread-58) [zigate] File already existing, make a backup before 2018-12-06 02:40:12 ERROR (MainThread) [homeassistant.core] Error doing job: SSL handshake failed Traceback (most recent call last): File "uvloop/handles/stream.pyx", line 609, in uvloop.loop.UVStream._on_eof File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-06 02:40:12 ERROR (MainThread) [homeassistant.core] Error doing job: SSL error errno:1 reason: WRONG_VERSION_NUMBER Traceback (most recent call last): File "uvloop/sslproto.pyx", line 504, in uvloop.loop.SSLProtocol.data_received File "uvloop/sslproto.pyx", line 204, in uvloop.loop._SSLPipe.feed_ssldata File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake self._sslobj.do_handshake() ssl.SSLError: [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:841) 2018-12-06 02:41:10 DEBUG (Thread-59) [zigate] Auto saving /config/zigate.json 2018-12-06 02:41:10 DEBUG (Thread-59) [zigate] Saving persistent file 2018-12-06 02:41:10 DEBUG (Thread-59) [zigate] File already existing, make a backup before 2018-12-06 02:46:10 DEBUG (Thread-60) [zigate] Auto saving /config/zigate.json 2018-12-06 02:46:10 DEBUG (Thread-60) [zigate] Saving persistent file 2018-12-06 02:46:10 DEBUG (Thread-60) [zigate] File already existing, make a backup before 2018-12-06 02:51:10 DEBUG (Thread-61) [zigate] Auto saving /config/zigate.json 2018-12-06 02:51:10 DEBUG (Thread-61) [zigate] Saving persistent file 2018-12-06 02:51:10 DEBUG (Thread-61) [zigate] File already existing, make a backup before 2018-12-06 02:56:10 DEBUG (Thread-62) [zigate] Auto saving /config/zigate.json 2018-12-06 02:56:10 DEBUG (Thread-62) [zigate] Saving persistent file 2018-12-06 02:56:10 DEBUG (Thread-62) [zigate] File already existing, make a backup before 2018-12-06 03:01:10 DEBUG (Thread-63) [zigate] Auto saving /config/zigate.json 2018-12-06 03:01:10 DEBUG (Thread-63) [zigate] Saving persistent file 2018-12-06 03:01:10 DEBUG (Thread-63) [zigate] File already existing, make a backup before 2018-12-06 03:06:10 DEBUG (Thread-64) [zigate] Auto saving /config/zigate.json 2018-12-06 03:06:10 DEBUG (Thread-64) [zigate] Saving persistent file 2018-12-06 03:06:10 DEBUG (Thread-64) [zigate] File already existing, make a backup before 2018-12-06 03:11:10 DEBUG (Thread-65) [zigate] Auto saving /config/zigate.json 2018-12-06 03:11:10 DEBUG (Thread-65) [zigate] Saving persistent file 2018-12-06 03:11:10 DEBUG (Thread-65) [zigate] File already existing, make a backup before 2018-12-06 03:16:10 DEBUG (Thread-66) [zigate] Auto saving /config/zigate.json 2018-12-06 03:16:10 DEBUG (Thread-66) [zigate] Saving persistent file 2018-12-06 03:16:10 DEBUG (Thread-66) [zigate] File already existing, make a backup before 2018-12-06 03:21:10 DEBUG (Thread-67) [zigate] Auto saving /config/zigate.json 2018-12-06 03:21:10 DEBUG (Thread-67) [zigate] Saving persistent file 2018-12-06 03:21:10 DEBUG (Thread-67) [zigate] File already existing, make a backup before 2018-12-06 03:26:10 DEBUG (Thread-68) [zigate] Auto saving /config/zigate.json 2018-12-06 03:26:10 DEBUG (Thread-68) [zigate] Saving persistent file 2018-12-06 03:26:10 DEBUG (Thread-68) [zigate] File already existing, make a backup before 2018-12-06 03:31:10 DEBUG (Thread-69) [zigate] Auto saving /config/zigate.json 2018-12-06 03:31:10 DEBUG (Thread-69) [zigate] Saving persistent file 2018-12-06 03:31:10 DEBUG (Thread-69) [zigate] File already existing, make a backup before 2018-12-06 03:36:10 DEBUG (Thread-70) [zigate] Auto saving /config/zigate.json 2018-12-06 03:36:10 DEBUG (Thread-70) [zigate] Saving persistent file 2018-12-06 03:36:10 DEBUG (Thread-70) [zigate] File already existing, make a backup before 2018-12-06 03:41:10 DEBUG (Thread-71) [zigate] Auto saving /config/zigate.json 2018-12-06 03:41:10 DEBUG (Thread-71) [zigate] Saving persistent file 2018-12-06 03:41:10 DEBUG (Thread-71) [zigate] File already existing, make a backup before 2018-12-06 03:46:10 DEBUG (Thread-72) [zigate] Auto saving /config/zigate.json 2018-12-06 03:46:10 DEBUG (Thread-72) [zigate] Saving persistent file 2018-12-06 03:46:10 DEBUG (Thread-72) [zigate] File already existing, make a backup before 2018-12-06 03:51:10 DEBUG (Thread-73) [zigate] Auto saving /config/zigate.json 2018-12-06 03:51:10 DEBUG (Thread-73) [zigate] Saving persistent file 2018-12-06 03:51:10 DEBUG (Thread-73) [zigate] File already existing, make a backup before 2018-12-06 03:56:10 DEBUG (Thread-74) [zigate] Auto saving /config/zigate.json 2018-12-06 03:56:10 DEBUG (Thread-74) [zigate] Saving persistent file 2018-12-06 03:56:10 DEBUG (Thread-74) [zigate] File already existing, make a backup before 2018-12-06 04:01:10 DEBUG (Thread-75) [zigate] Auto saving /config/zigate.json 2018-12-06 04:01:10 DEBUG (Thread-75) [zigate] Saving persistent file 2018-12-06 04:01:10 DEBUG (Thread-75) [zigate] File already existing, make a backup before 2018-12-06 04:06:10 DEBUG (Thread-76) [zigate] Auto saving /config/zigate.json 2018-12-06 04:06:10 DEBUG (Thread-76) [zigate] Saving persistent file 2018-12-06 04:06:10 DEBUG (Thread-76) [zigate] File already existing, make a backup before 2018-12-06 04:11:10 DEBUG (Thread-77) [zigate] Auto saving /config/zigate.json 2018-12-06 04:11:10 DEBUG (Thread-77) [zigate] Saving persistent file 2018-12-06 04:11:10 DEBUG (Thread-77) [zigate] File already existing, make a backup before 2018-12-06 04:16:10 DEBUG (Thread-78) [zigate] Auto saving /config/zigate.json 2018-12-06 04:16:10 DEBUG (Thread-78) [zigate] Saving persistent file 2018-12-06 04:16:10 DEBUG (Thread-78) [zigate] File already existing, make a backup before 2018-12-06 04:21:10 DEBUG (Thread-79) [zigate] Auto saving /config/zigate.json 2018-12-06 04:21:10 DEBUG (Thread-79) [zigate] Saving persistent file 2018-12-06 04:21:10 DEBUG (Thread-79) [zigate] File already existing, make a backup before 2018-12-06 04:26:10 DEBUG (Thread-80) [zigate] Auto saving /config/zigate.json 2018-12-06 04:26:10 DEBUG (Thread-80) [zigate] Saving persistent file 2018-12-06 04:26:10 DEBUG (Thread-80) [zigate] File already existing, make a backup before 2018-12-06 04:31:10 DEBUG (Thread-81) [zigate] Auto saving /config/zigate.json 2018-12-06 04:31:10 DEBUG (Thread-81) [zigate] Saving persistent file 2018-12-06 04:31:10 DEBUG (Thread-81) [zigate] File already existing, make a backup before 2018-12-06 04:36:10 DEBUG (Thread-82) [zigate] Auto saving /config/zigate.json 2018-12-06 04:36:10 DEBUG (Thread-82) [zigate] Saving persistent file 2018-12-06 04:36:10 DEBUG (Thread-82) [zigate] File already existing, make a backup before 2018-12-06 04:41:10 DEBUG (Thread-83) [zigate] Auto saving /config/zigate.json 2018-12-06 04:41:10 DEBUG (Thread-83) [zigate] Saving persistent file 2018-12-06 04:41:10 DEBUG (Thread-83) [zigate] File already existing, make a backup before 2018-12-06 04:46:10 DEBUG (Thread-84) [zigate] Auto saving /config/zigate.json 2018-12-06 04:46:10 DEBUG (Thread-84) [zigate] Saving persistent file 2018-12-06 04:46:10 DEBUG (Thread-84) [zigate] File already existing, make a backup before 2018-12-06 04:51:10 DEBUG (Thread-85) [zigate] Auto saving /config/zigate.json 2018-12-06 04:51:10 DEBUG (Thread-85) [zigate] Saving persistent file 2018-12-06 04:51:10 DEBUG (Thread-85) [zigate] File already existing, make a backup before 2018-12-06 04:56:10 DEBUG (Thread-86) [zigate] Auto saving /config/zigate.json 2018-12-06 04:56:10 DEBUG (Thread-86) [zigate] Saving persistent file 2018-12-06 04:56:10 DEBUG (Thread-86) [zigate] File already existing, make a backup before 2018-12-06 05:01:10 DEBUG (Thread-87) [zigate] Auto saving /config/zigate.json 2018-12-06 05:01:10 DEBUG (Thread-87) [zigate] Saving persistent file 2018-12-06 05:01:10 DEBUG (Thread-87) [zigate] File already existing, make a backup before 2018-12-06 05:06:10 DEBUG (Thread-88) [zigate] Auto saving /config/zigate.json 2018-12-06 05:06:10 DEBUG (Thread-88) [zigate] Saving persistent file 2018-12-06 05:06:10 DEBUG (Thread-88) [zigate] File already existing, make a backup before 2018-12-06 05:11:10 DEBUG (Thread-89) [zigate] Auto saving /config/zigate.json 2018-12-06 05:11:10 DEBUG (Thread-89) [zigate] Saving persistent file 2018-12-06 05:11:10 DEBUG (Thread-89) [zigate] File already existing, make a backup before 2018-12-06 05:16:10 DEBUG (Thread-90) [zigate] Auto saving /config/zigate.json 2018-12-06 05:16:10 DEBUG (Thread-90) [zigate] Saving persistent file 2018-12-06 05:16:10 DEBUG (Thread-90) [zigate] File already existing, make a backup before 2018-12-06 05:21:10 DEBUG (Thread-91) [zigate] Auto saving /config/zigate.json 2018-12-06 05:21:10 DEBUG (Thread-91) [zigate] Saving persistent file 2018-12-06 05:21:10 DEBUG (Thread-91) [zigate] File already existing, make a backup before 2018-12-06 05:26:10 DEBUG (Thread-92) [zigate] Auto saving /config/zigate.json 2018-12-06 05:26:10 DEBUG (Thread-92) [zigate] Saving persistent file 2018-12-06 05:26:10 DEBUG (Thread-92) [zigate] File already existing, make a backup before 2018-12-06 05:31:10 DEBUG (Thread-93) [zigate] Auto saving /config/zigate.json 2018-12-06 05:31:10 DEBUG (Thread-93) [zigate] Saving persistent file 2018-12-06 05:31:10 DEBUG (Thread-93) [zigate] File already existing, make a backup before 2018-12-06 05:36:10 DEBUG (Thread-94) [zigate] Auto saving /config/zigate.json 2018-12-06 05:36:10 DEBUG (Thread-94) [zigate] Saving persistent file 2018-12-06 05:36:10 DEBUG (Thread-94) [zigate] File already existing, make a backup before 2018-12-06 05:41:10 DEBUG (Thread-95) [zigate] Auto saving /config/zigate.json 2018-12-06 05:41:10 DEBUG (Thread-95) [zigate] Saving persistent file 2018-12-06 05:41:10 DEBUG (Thread-95) [zigate] File already existing, make a backup before 2018-12-06 05:46:10 DEBUG (Thread-96) [zigate] Auto saving /config/zigate.json 2018-12-06 05:46:10 DEBUG (Thread-96) [zigate] Saving persistent file 2018-12-06 05:46:10 DEBUG (Thread-96) [zigate] File already existing, make a backup before 2018-12-06 05:51:10 DEBUG (Thread-97) [zigate] Auto saving /config/zigate.json 2018-12-06 05:51:10 DEBUG (Thread-97) [zigate] Saving persistent file 2018-12-06 05:51:10 DEBUG (Thread-97) [zigate] File already existing, make a backup before 2018-12-06 05:56:10 DEBUG (Thread-98) [zigate] Auto saving /config/zigate.json 2018-12-06 05:56:10 DEBUG (Thread-98) [zigate] Saving persistent file 2018-12-06 05:56:10 DEBUG (Thread-98) [zigate] File already existing, make a backup before 2018-12-06 06:01:10 DEBUG (Thread-99) [zigate] Auto saving /config/zigate.json 2018-12-06 06:01:10 DEBUG (Thread-99) [zigate] Saving persistent file 2018-12-06 06:01:10 DEBUG (Thread-99) [zigate] File already existing, make a backup before 2018-12-06 06:06:10 DEBUG (Thread-100) [zigate] Auto saving /config/zigate.json 2018-12-06 06:06:10 DEBUG (Thread-100) [zigate] Saving persistent file 2018-12-06 06:06:10 DEBUG (Thread-100) [zigate] File already existing, make a backup before 2018-12-06 06:11:10 DEBUG (Thread-101) [zigate] Auto saving /config/zigate.json 2018-12-06 06:11:10 DEBUG (Thread-101) [zigate] Saving persistent file 2018-12-06 06:11:10 DEBUG (Thread-101) [zigate] File already existing, make a backup before 2018-12-06 06:16:10 DEBUG (Thread-102) [zigate] Auto saving /config/zigate.json 2018-12-06 06:16:10 DEBUG (Thread-102) [zigate] Saving persistent file 2018-12-06 06:16:10 DEBUG (Thread-102) [zigate] File already existing, make a backup before 2018-12-06 06:21:10 DEBUG (Thread-103) [zigate] Auto saving /config/zigate.json 2018-12-06 06:21:10 DEBUG (Thread-103) [zigate] Saving persistent file 2018-12-06 06:21:10 DEBUG (Thread-103) [zigate] File already existing, make a backup before 2018-12-06 06:26:10 DEBUG (Thread-104) [zigate] Auto saving /config/zigate.json 2018-12-06 06:26:10 DEBUG (Thread-104) [zigate] Saving persistent file 2018-12-06 06:26:10 DEBUG (Thread-104) [zigate] File already existing, make a backup before 2018-12-06 06:31:10 DEBUG (Thread-105) [zigate] Auto saving /config/zigate.json 2018-12-06 06:31:10 DEBUG (Thread-105) [zigate] Saving persistent file 2018-12-06 06:31:10 DEBUG (Thread-105) [zigate] File already existing, make a backup before 2018-12-06 06:36:10 DEBUG (Thread-106) [zigate] Auto saving /config/zigate.json 2018-12-06 06:36:10 DEBUG (Thread-106) [zigate] Saving persistent file 2018-12-06 06:36:10 DEBUG (Thread-106) [zigate] File already existing, make a backup before 2018-12-06 06:41:11 DEBUG (Thread-107) [zigate] Auto saving /config/zigate.json 2018-12-06 06:41:11 DEBUG (Thread-107) [zigate] Saving persistent file 2018-12-06 06:41:11 DEBUG (Thread-107) [zigate] File already existing, make a backup before 2018-12-06 06:46:11 DEBUG (Thread-108) [zigate] Auto saving /config/zigate.json 2018-12-06 06:46:11 DEBUG (Thread-108) [zigate] Saving persistent file 2018-12-06 06:46:11 DEBUG (Thread-108) [zigate] File already existing, make a backup before 2018-12-06 06:51:11 DEBUG (Thread-109) [zigate] Auto saving /config/zigate.json 2018-12-06 06:51:11 DEBUG (Thread-109) [zigate] Saving persistent file 2018-12-06 06:51:11 DEBUG (Thread-109) [zigate] File already existing, make a backup before 2018-12-06 06:56:11 DEBUG (Thread-110) [zigate] Auto saving /config/zigate.json 2018-12-06 06:56:11 DEBUG (Thread-110) [zigate] Saving persistent file 2018-12-06 06:56:11 DEBUG (Thread-110) [zigate] File already existing, make a backup before 2018-12-06 07:01:11 DEBUG (Thread-111) [zigate] Auto saving /config/zigate.json 2018-12-06 07:01:11 DEBUG (Thread-111) [zigate] Saving persistent file 2018-12-06 07:01:11 DEBUG (Thread-111) [zigate] File already existing, make a backup before 2018-12-06 07:06:11 DEBUG (Thread-112) [zigate] Auto saving /config/zigate.json 2018-12-06 07:06:11 DEBUG (Thread-112) [zigate] Saving persistent file 2018-12-06 07:06:11 DEBUG (Thread-112) [zigate] File already existing, make a backup before 2018-12-06 07:11:11 DEBUG (Thread-113) [zigate] Auto saving /config/zigate.json 2018-12-06 07:11:11 DEBUG (Thread-113) [zigate] Saving persistent file 2018-12-06 07:11:11 DEBUG (Thread-113) [zigate] File already existing, make a backup before 2018-12-06 07:16:11 DEBUG (Thread-114) [zigate] Auto saving /config/zigate.json 2018-12-06 07:16:11 DEBUG (Thread-114) [zigate] Saving persistent file 2018-12-06 07:16:11 DEBUG (Thread-114) [zigate] File already existing, make a backup before 2018-12-06 07:21:11 DEBUG (Thread-115) [zigate] Auto saving /config/zigate.json 2018-12-06 07:21:11 DEBUG (Thread-115) [zigate] Saving persistent file 2018-12-06 07:21:11 DEBUG (Thread-115) [zigate] File already existing, make a backup before 2018-12-06 07:26:11 DEBUG (Thread-116) [zigate] Auto saving /config/zigate.json 2018-12-06 07:26:11 DEBUG (Thread-116) [zigate] Saving persistent file 2018-12-06 07:26:11 DEBUG (Thread-116) [zigate] File already existing, make a backup before 2018-12-06 07:31:11 DEBUG (Thread-117) [zigate] Auto saving /config/zigate.json 2018-12-06 07:31:11 DEBUG (Thread-117) [zigate] Saving persistent file 2018-12-06 07:31:11 DEBUG (Thread-117) [zigate] File already existing, make a backup before 2018-12-06 07:36:11 DEBUG (Thread-118) [zigate] Auto saving /config/zigate.json 2018-12-06 07:36:11 DEBUG (Thread-118) [zigate] Saving persistent file 2018-12-06 07:36:11 DEBUG (Thread-118) [zigate] File already existing, make a backup before 2018-12-06 07:41:11 DEBUG (Thread-119) [zigate] Auto saving /config/zigate.json 2018-12-06 07:41:11 DEBUG (Thread-119) [zigate] Saving persistent file 2018-12-06 07:41:11 DEBUG (Thread-119) [zigate] File already existing, make a backup before 2018-12-06 07:46:11 DEBUG (Thread-120) [zigate] Auto saving /config/zigate.json 2018-12-06 07:46:11 DEBUG (Thread-120) [zigate] Saving persistent file 2018-12-06 07:46:11 DEBUG (Thread-120) [zigate] File already existing, make a backup before 2018-12-06 07:51:11 DEBUG (Thread-121) [zigate] Auto saving /config/zigate.json 2018-12-06 07:51:11 DEBUG (Thread-121) [zigate] Saving persistent file 2018-12-06 07:51:11 DEBUG (Thread-121) [zigate] File already existing, make a backup before 2018-12-06 07:56:11 DEBUG (Thread-122) [zigate] Auto saving /config/zigate.json 2018-12-06 07:56:11 DEBUG (Thread-122) [zigate] Saving persistent file 2018-12-06 07:56:11 DEBUG (Thread-122) [zigate] File already existing, make a backup before 2018-12-06 08:01:11 DEBUG (Thread-123) [zigate] Auto saving /config/zigate.json 2018-12-06 08:01:11 DEBUG (Thread-123) [zigate] Saving persistent file 2018-12-06 08:01:11 DEBUG (Thread-123) [zigate] File already existing, make a backup before 2018-12-06 08:06:11 DEBUG (Thread-124) [zigate] Auto saving /config/zigate.json 2018-12-06 08:06:11 DEBUG (Thread-124) [zigate] Saving persistent file 2018-12-06 08:06:11 DEBUG (Thread-124) [zigate] File already existing, make a backup before 2018-12-06 08:11:11 DEBUG (Thread-125) [zigate] Auto saving /config/zigate.json 2018-12-06 08:11:11 DEBUG (Thread-125) [zigate] Saving persistent file 2018-12-06 08:11:11 DEBUG (Thread-125) [zigate] File already existing, make a backup before 2018-12-06 08:16:11 DEBUG (Thread-126) [zigate] Auto saving /config/zigate.json 2018-12-06 08:16:11 DEBUG (Thread-126) [zigate] Saving persistent file 2018-12-06 08:16:11 DEBUG (Thread-126) [zigate] File already existing, make a backup before 2018-12-06 08:21:11 DEBUG (Thread-127) [zigate] Auto saving /config/zigate.json 2018-12-06 08:21:11 DEBUG (Thread-127) [zigate] Saving persistent file 2018-12-06 08:21:11 DEBUG (Thread-127) [zigate] File already existing, make a backup before 2018-12-06 08:26:11 DEBUG (Thread-128) [zigate] Auto saving /config/zigate.json 2018-12-06 08:26:11 DEBUG (Thread-128) [zigate] Saving persistent file 2018-12-06 08:26:11 DEBUG (Thread-128) [zigate] File already existing, make a backup before 2018-12-06 08:31:11 DEBUG (Thread-129) [zigate] Auto saving /config/zigate.json 2018-12-06 08:31:11 DEBUG (Thread-129) [zigate] Saving persistent file 2018-12-06 08:31:11 DEBUG (Thread-129) [zigate] File already existing, make a backup before

Second log: 2018-12-06 08:38:12 DEBUG (SyncWorker_3) [zigate] Saving persistent file 2018-12-06 08:38:12 DEBUG (SyncWorker_3) [zigate] File already existing, make a backup before 2018-12-06 08:39:06 DEBUG (SyncWorker_2) [zigate] Try loading persistent file 2018-12-06 08:39:06 DEBUG (SyncWorker_2) [zigate] Load success 2018-12-06 08:39:07 DEBUG (SyncWorker_2) [zigate] ZiGate found on port 9999 2018-12-06 08:39:07 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0010 2018-12-06 08:39:07 DEBUG (SyncWorker_2) [zigate] Msg to send b'0010000010' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'01021010021002101003' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0010 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000010' 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:16, error:b'', rssi:0 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8010: b'0001030d' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] STATUS code to command 0x0010:0 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8010 - Version list : major:1, installer:30d, rssi:0, version:3.0d 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Waiting for message 0x8010 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Stop waiting, got message 0x8010 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0021 b'\x03\x18\xc8\x00' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Msg to send b'00210004f60318c800' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'0102102102100214f6021318c8021003' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0021 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000021' 2018-12-06 08:39:08 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:33, error:b'', rssi:0 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] STATUS code to command 0x0021:0 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0023 b'\x00' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Msg to send b'002300012200' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'010210230210021122021003' 2018-12-06 08:39:08 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0023 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000023' 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:35, error:b'', rssi:0 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] STATUS code to command 0x0023:0 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Check network state 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0024 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Msg to send b'0024000024' 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'01021024021002102403' 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0024 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000024' 2018-12-06 08:39:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:36, error:b'', rssi:0 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] STATUS code to command 0x0024:0 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0009 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Msg to send b'0009000009' 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'010210021902100210021903' 2018-12-06 08:39:09 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0009 2018-12-06 08:39:12 WARNING (SyncWorker_2) [zigate] No response after command 0x0009 2018-12-06 08:39:12 ERROR (SyncWorker_2) [zigate] Failed to get network state 2018-12-06 08:39:12 DEBUG (SyncWorker_2) [zigate] Network is down, start it 2018-12-06 08:39:12 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0024 2018-12-06 08:39:12 DEBUG (SyncWorker_2) [zigate] Msg to send b'0024000024' 2018-12-06 08:39:12 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'01021024021002102403' 2018-12-06 08:39:12 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0024 2018-12-06 08:39:15 WARNING (SyncWorker_2) [zigate] No response after command 0x0024 2018-12-06 08:39:15 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0015 2018-12-06 08:39:15 DEBUG (SyncWorker_2) [zigate] Msg to send b'0015000015' 2018-12-06 08:39:15 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'01021015021002101503' 2018-12-06 08:39:15 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0015 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000009' 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:9, error:b'', rssi:0 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8009: b'000000158d0001edb71264f243a94141081980450b' 2018-12-06 08:39:16 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8009 - Network state response : addr:0000, ieee:00158d0001edb712, pan:25842, extend_pan:4875499819169841221, channel:11, rssi:0 2018-12-06 08:39:18 WARNING (SyncWorker_2) [zigate] No response after command 0x0015 2018-12-06 08:39:18 DEBUG (SyncWorker_2) [zigate] Check Need refresh Device 00158d00024e6062 (baf9) lumi.sensor_magnet.aq2 2018-12-06 08:39:18 DEBUG (SyncWorker_2) [zigate] Check Need refresh Device 00158d0002536f0b (5cdd) lumi.sensor_magnet.aq2 2018-12-06 08:39:18 DEBUG (SyncWorker_2) [zigate] Auto saving /config/zigate.json 2018-12-06 08:39:18 DEBUG (SyncWorker_2) [zigate] Saving persistent file 2018-12-06 08:39:18 DEBUG (SyncWorker_2) [zigate] File already existing, make a backup before 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000015' 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:21, error:b'', rssi:0 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8015: b'005cdd00158d0002536f0b006c01baf900158d00024e60620057' 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8015 - Device list : devices:[OrderedDict([('id', 0), ('addr', '5cdd'), ('ieee', '00158d0002536f0b'), ('power_type', 0), ('rssi', 108)]), OrderedDict([('id', 1), ('addr', 'baf9'), ('ieee', '00158d00024e6062'), ('power_type', 0), ('rssi', 87)])], rssi:0 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Known devices in zigate : {'5cdd', 'baf9'} 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Previous devices missing : set() 2018-12-06 08:39:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_DEVICE_UPDATED 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'ffbaf901000600000010000101' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:255, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:True, rssi:114 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'00baf901000600000010000100' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:0, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:False, rssi:111 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'f15cdd01000600000010000101' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:241, addr:5cdd, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:True, rssi:99 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'f25cdd01000600000010000100' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:242, addr:5cdd, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:False, rssi:102 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'01baf90100000005004200166c756d692e73656e736f725f6d61676e65742e617132' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:1, addr:baf9, endpoint:1, cluster:0, attribute:5, status:0, data_type:66, size:22, data:lumi.sensor_magnet.aq2, rssi:102 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'01baf9010000ff010042001d0121db0b0328170421a81305211d00062404000000000a210000641000' 2018-12-06 08:41:09 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:1, addr:baf9, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121db0b0328170421a81305211d00062404000000000a210000641000, rssi:102 2018-12-06 08:41:29 DEBUG (SyncWorker_4) [zigate] Saving persistent file 2018-12-06 08:41:29 DEBUG (SyncWorker_4) [zigate] File already existing, make a backup before 2018-12-06 08:41:39 ERROR (MainThread) [homeassistant.components.hassio.handler] Timeout on /homeassistant/restart request <<<<<<-------------- Start of second reboot 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] Try loading persistent file 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] Load success 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] ZiGate found on port 9999 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0010 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] Msg to send b'0010000010' 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'01021010021002101003' 2018-12-06 08:42:06 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0010 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000010' 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:16, error:b'', rssi:0 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8010: b'0001030d' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0010:0 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8010 - Version list : major:1, installer:30d, rssi:0, version:3.0d 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for message 0x8010 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Stop waiting, got message 0x8010 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0021 b'\x03\x18\xc8\x00' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Msg to send b'00210004f60318c800' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'0102102102100214f6021318c8021003' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0021 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000021' 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:33, error:b'', rssi:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0021:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0023 b'\x00' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Msg to send b'002300012200' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'010210230210021122021003' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0023 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000023' 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:35, error:b'', rssi:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0023:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Check network state 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0024 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Msg to send b'0024000024' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'01021024021002102403' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0024 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000024' 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:36, error:b'', rssi:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0024:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0009 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Msg to send b'0009000009' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'010210021902100210021903' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0009 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000009' 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:9, error:b'', rssi:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0009:0 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8009: b'000000158d0001edb71264f243a94141081980450b' 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Waiting for message 0x8009 2018-12-06 08:42:07 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8009 - Network state response : addr:0000, ieee:00158d0001edb712, pan:25842, extend_pan:4875499819169841221, channel:11, rssi:0 2018-12-06 08:42:07 DEBUG (SyncWorker_4) [zigate] Stop waiting, got message 0x8009 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0015 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Msg to send b'0015000015' 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'01021015021002101503' 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0015 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8000: b'00000015' 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:0, packet_type:21, error:b'', rssi:0 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0015:0 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8015: b'005cdd00158d0002536f0b006901baf900158d00024e6062005d' 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Waiting for message 0x8015 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8015 - Device list : devices:[OrderedDict([('id', 0), ('addr', '5cdd'), ('ieee', '00158d0002536f0b'), ('power_type', 0), ('rssi', 105)]), OrderedDict([('id', 1), ('addr', 'baf9'), ('ieee', '00158d00024e6062'), ('power_type', 0), ('rssi', 93)])], rssi:0 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Stop waiting, got message 0x8015 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Known devices in zigate : {'5cdd', 'baf9'} 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Check Need refresh Device 00158d00024e6062 (baf9) lumi.sensor_magnet.aq2 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Previous devices missing : set() 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Check Need refresh Device 00158d0002536f0b (5cdd) lumi.sensor_magnet.aq2 2018-12-06 08:42:08 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_DEVICE_UPDATED 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Auto saving /config/zigate.json 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] Saving persistent file 2018-12-06 08:42:08 DEBUG (SyncWorker_4) [zigate] File already existing, make a backup before 2018-12-06 08:42:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:42:21 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'02baf901000600000010000101' 2018-12-06 08:42:21 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:2, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:True, rssi:96 2018-12-06 08:42:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:42:21 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-06 08:42:35 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:42:35 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'03baf901000600000010000100' 2018-12-06 08:42:35 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:3, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:False, rssi:105 2018-12-06 08:42:35 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:42:35 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'04baf901000600000010000101' 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:4, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:True, rssi:96 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'05baf901000600000010000100' 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:5, addr:baf9, endpoint:1, cluster:6, attribute:0, status:0, data_type:16, size:1, data:False, rssi:93 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'06baf9010000ff01004200090421a8130a21000073' 2018-12-06 08:42:36 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:6, addr:baf9, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:9, data:0421a8130a21000073, rssi:93

doudz commented 5 years ago

Looks like https://github.com/Jeedom-Zigate/jeedom-plugin-zigate/issues/81 Zigate seems freezing... I don't have the problem

I think I have to periodically check the zigate with some kind of NOOP command

Are you using firmware 3.0e ? or 3.0d ?

jalme commented 5 years ago

Hi,

3.0d and wifi 1.3

João

doudz commented 5 years ago

ok, same firmware as @ioull maybe it's a problem of 3.0d

jalme commented 5 years ago

Ok. Should I then upgrade and test?

doudz commented 5 years ago

If you can, yes please upgrade to 3.0e (be aware that you'll need to pair your devices again)

jalme commented 5 years ago

By any chance to you know of any good step by step guide to do the upgrade?

doudz commented 5 years ago

Steps :

Put zigate in download mode (push button while plugging USB)
Backup current old firmware (just in case)
Flash new firmware
Put zigate in running mode (don't push the button during plugging USB)
Erase EEPROM (command 0x0012)
Pair device
Enjoy

https://zigate.fr/wiki/mise-a-jour-de-la-zigate/

jalme commented 5 years ago

Hi,

I've updated the firmware but now my Wifi connection is acting up again. Very unreliable.

I have updated the ZiGate issue related to Wifi. Will try to see if I can make it work better and then return to this issue.

Thanks

jalme commented 5 years ago

Hi,

I have now switch ZiGate to TTL but after re-pairing the devices I have now a new device which I don't know where it came from.

Log: 2018-12-08 15:28:08 DEBUG (SyncWorker_1) [zigate] Check Need refresh Device 0000000000000000 (0000) 2018-12-08 15:28:08 DEBUG (SyncWorker_1) [zigate] Need refresh : no type 2018-12-08 15:28:08 DEBUG (SyncWorker_1) [zigate] Need refresh : no endpoints 2018-12-08 15:28:08 DEBUG (SyncWorker_1) [zigate] Dispatch ZIGATE_DEVICE_NEED_REFRESH

How can I remove it?

João

doudz commented 5 years ago

The problem has been fixed in lib 0.24, I'll close this issue as soon as 0.24 will be released

doudz commented 5 years ago

@jalme you can call the service zigate.cleanup

doudz commented 5 years ago

Fixed

doudz commented 5 years ago

Not solved, now I have the problem...

jalme commented 5 years ago

Weird. I have used cleanup command but the device with address 0000 is still there.

Also with TTL the sensors (or the coordinator) are more steady in reporting state. What setup are you using?

doudz commented 5 years ago

I think I finally found the problem. Now there will be an error entry in log instead of a silent crash

jalme commented 5 years ago

Hi,

Sensors stopped updating for 1 hour at least.

Logs

2018-12-22 15:31:31 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b"\x01\x81\x02\x12\x02\x10*\x9a'\xd8\x9e\x02\x11\x02\x10\x02\x10\xff\x02\x11\x02\x10B\x02\x10\x1d\x02\x11!\xbd\x02\x1b\x02\x13(\x17\x02\x14!\xa8\x13\x02\x15!\x86\x02\x10\x02\x16$\x02\x12\x02\x10\x02\x10\x02\x11\x02\x11\x02\x1a!\x02\x10\x02\x10d\x10\x02\x10\x1b\x03"
2018-12-22 15:31:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 15:31:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'27d89e010000ff010042001d0121bd0b0328170421a81305218600062402000001010a210000641000'
2018-12-22 15:31:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:39, addr:d89e, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121bd0b0328170421a81305218600062402000001010a210000641000, rssi:27
2018-12-22 15:31:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 15:31:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 15:33:11 DEBUG (Thread-296) [zigate] Auto saving /config/zigate.json
2018-12-22 15:33:11 DEBUG (Thread-296) [zigate] Saving persistent file
2018-12-22 15:33:11 DEBUG (Thread-296) [zigate] File already existing, make a backup before
2018-12-22 15:38:11 DEBUG (Thread-297) [zigate] Auto saving /config/zigate.json
2018-12-22 15:38:11 DEBUG (Thread-297) [zigate] Saving persistent file
2018-12-22 15:38:11 DEBUG (Thread-297) [zigate] File already existing, make a backup before
2018-12-22 15:39:49 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x81\x02\x12\x02\x102\xbdt{\x02\x1e\x02\x11\x02\x10\x02\x10\xff\x02\x11\x02\x10B\x02\x10%\x02\x11!\x9f\x02\x1b\x02\x14!\xa8\x13\x02\x15!\x02\x19\x02\x10\x02\x16$\x02\x11\x02\x10\x02\x10\x02\x10\x02\x10d)G\x02\x17e!6\x1ef+\xb6\x8b\x02\x11\x02\x10\x02\x1a!\x02\x10\x02\x10\x8a\x03'
2018-12-22 15:39:49 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 15:39:49 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'747b0e010000ff010042002501219f0b0421a8130521090006240100000000642947076521361e662bb68b01000a210000'
2018-12-22 15:39:49 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:116, addr:7b0e, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:37, data:01219f0b0421a8130521090006240100000000642947076521361e662bb68b01000a210000, rssi:138
2018-12-22 15:39:49 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 15:39:49 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 15:43:11 DEBUG (Thread-298) [zigate] Auto saving /config/zigate.json
2018-12-22 15:43:11 DEBUG (Thread-298) [zigate] Saving persistent file
2018-12-22 15:43:11 DEBUG (Thread-298) [zigate] File already existing, make a backup before
2018-12-22 15:48:11 DEBUG (Thread-299) [zigate] Auto saving /config/zigate.json
2018-12-22 15:48:11 DEBUG (Thread-299) [zigate] Saving persistent file
2018-12-22 15:48:11 DEBUG (Thread-299) [zigate] File already existing, make a backup before
2018-12-22 15:52:16 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x81\x02\x12\x02\x102\x1b|o\xcb\x02\x11\x02\x10\x02\x10\xff\x02\x11\x02\x10B\x02\x10%\x02\x11!\xb3\x02\x1b\x02\x14!\xa8\x13\x02\x15!\x02\x1b\x02\x10\x02\x16$\x02\x11\x02\x10\x02\x10\x02\x10\x02\x10d)\x02\x1f\x02\x18e!\x92\x1bf+\xd6\x8b\x02\x11\x02\x10\x02\x1a!\x02\x10\x02\x10]\x03'
2018-12-22 15:52:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 15:52:16 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'7c6fcb010000ff01004200250121b30b0421a81305210b000624010000000064290f086521921b662bd68b01000a210000'
2018-12-22 15:52:16 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:124, addr:6fcb, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:37, data:0121b30b0421a81305210b000624010000000064290f086521921b662bd68b01000a210000, rssi:93
2018-12-22 15:52:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 15:52:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 15:53:11 DEBUG (Thread-300) [zigate] Auto saving /config/zigate.json
2018-12-22 15:53:11 DEBUG (Thread-300) [zigate] Saving persistent file
2018-12-22 15:53:11 DEBUG (Thread-300) [zigate] File already existing, make a backup before
2018-12-22 15:58:11 DEBUG (Thread-301) [zigate] Auto saving /config/zigate.json
2018-12-22 15:58:11 DEBUG (Thread-301) [zigate] Saving persistent file
2018-12-22 15:58:11 DEBUG (Thread-301) [zigate] File already existing, make a backup before
2018-12-22 16:02:46 ERROR (MainThread) [homeassistant.components.updater] Got unexpected response: None
2018-12-22 16:03:11 DEBUG (Thread-302) [zigate] Auto saving /config/zigate.json
2018-12-22 16:03:11 DEBUG (Thread-302) [zigate] Saving persistent file
2018-12-22 16:03:11 DEBUG (Thread-302) [zigate] File already existing, make a backup before
2018-12-22 16:08:11 DEBUG (Thread-303) [zigate] Auto saving /config/zigate.json
2018-12-22 16:08:11 DEBUG (Thread-303) [zigate] Saving persistent file
2018-12-22 16:08:11 DEBUG (Thread-303) [zigate] File already existing, make a backup before
2018-12-22 16:13:11 DEBUG (Thread-304) [zigate] Auto saving /config/zigate.json
2018-12-22 16:13:11 DEBUG (Thread-304) [zigate] Saving persistent file
2018-12-22 16:13:11 DEBUG (Thread-304) [zigate] File already existing, make a backup before
2018-12-22 16:18:11 DEBUG (Thread-305) [zigate] Auto saving /config/zigate.json
2018-12-22 16:18:11 DEBUG (Thread-305) [zigate] Saving persistent file
2018-12-22 16:18:11 DEBUG (Thread-305) [zigate] File already existing, make a backup before
2018-12-22 16:21:45 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x81\x02\x12\x02\x10*\x9f(\xd8\x9e\x02\x11\x02\x10\x02\x10'
2018-12-22 16:21:45 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\xff\x02\x11\x02\x10B\x02\x10\x1d\x02\x11!\xbd\x02\x1b\x02\x13(\x17\x02\x14!\xa8\x13\x02\x15!\x86\x02\x10\x02\x16$\x02\x11\x02\x10\x02\x10\x02\x10\x02\x10\x02\x1a!\x02\x10\x02\x10d\x10\x02\x10\x12\x03'
2018-12-22 16:21:45 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 16:21:45 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'28d89e010000ff010042001d0121bd0b0328170421a81305218600062401000000000a210000641000'
2018-12-22 16:21:45 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:40, addr:d89e, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:29, data:0121bd0b0328170421a81305218600062401000000000a210000641000, rssi:18
2018-12-22 16:21:45 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 16:21:45 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 16:23:11 DEBUG (Thread-306) [zigate] Auto saving /config/zigate.json
2018-12-22 16:23:11 DEBUG (Thread-306) [zigate] Saving persistent file
2018-12-22 16:23:11 DEBUG (Thread-306) [zigate] File already existing, make a backup before
2018-12-22 16:28:11 DEBUG (Thread-307) [zigate] Auto saving /config/zigate.json
2018-12-22 16:28:11 DEBUG (Thread-307) [zigate] Saving persistent file
2018-12-22 16:28:11 DEBUG (Thread-307) [zigate] File already existing, make a backup before
2018-12-22 16:33:11 DEBUG (Thread-308) [zigate] Auto saving /config/zigate.json
2018-12-22 16:33:11 DEBUG (Thread-308) [zigate] Saving persistent file
2018-12-22 16:33:11 DEBUG (Thread-308) [zigate] File already existing, make a backup before
2018-12-22 16:38:11 DEBUG (Thread-309) [zigate] Auto saving /config/zigate.json
2018-12-22 16:38:11 DEBUG (Thread-309) [zigate] Saving persistent file
2018-12-22 16:38:11 DEBUG (Thread-309) [zigate] File already existing, make a backup before
2018-12-22 16:39:30 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b"\x01\x81\x02\x12\x02\x102\xdeu{\x02\x1e\x02\x11\x02\x10\x02\x10\xff\x02\x11\x02\x10B\x02\x10%\x02\x11!\x9f\x02\x1b\x02\x14!\xa8\x13\x02\x15!\x02\x19\x02\x10\x02\x16$\x02\x11\x02\x10\x02\x10\x02\x10\x02\x10d)E\x02\x17e!'\x1ef+\xc0\x8b\x02\x11\x02\x10\x02\x1a!\x02\x10\x02\x10\x8d\x03"
2018-12-22 16:39:30 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 16:39:30 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'757b0e010000ff010042002501219f0b0421a8130521090006240100000000642945076521271e662bc08b01000a210000'
2018-12-22 16:39:30 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:117, addr:7b0e, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:37, data:01219f0b0421a8130521090006240100000000642945076521271e662bc08b01000a210000, rssi:141
2018-12-22 16:39:30 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 16:39:30 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED

jalme commented 5 years ago

Just as FYI sensors are not updating since 14:52 at least (Temperature sensors for example last update).

jalme commented 5 years ago

Logs before:

2018-12-22 14:52:13 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b"\x01\x81\x02\x12\x02\x10\x02\x1f0xo\xcb\x02\x11\x02\x14\x02\x12\x02\x10\x02\x10\x02\x10)\x02\x10\x02\x12\x02\x18\x19]\x03\x01\x81\x02\x12\x02\x10\x02\x1f\xadyo\xcb\x02\x11\x02\x14\x02\x15\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x12\x1b\x99]\x03\x01\x81\x02\x12\x02\x10\x02\x1f\xd4zo\xcb\x02\x11\x02\x14\x02\x13\x02\x10\x02\x10\x02\x10)\x02\x10\x02\x12\x02\x13\xf5]\x03\x01\x81\x02\x12\x02\x10\x02\x1e\xcazo\xcb\x02\x11\x02\x14\x02\x13\x02\x10\x14\x02\x10(\x02\x10\x02\x11\xff]\x03\x01\x81\x02\x12\x02\x10\x02\x1f\x83zo\xcb\x02\x11\x02\x14\x02\x13\x02\x10\x10\x02\x10)\x02\x10\x02\x12'\x96]\x03"
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'786fcb0104020000002900020819'
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:120, addr:6fcb, endpoint:1, cluster:1026, attribute:0, status:0, data_type:41, size:2, data:2073, rssi:93
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'796fcb0104050000002100021b99'
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:121, addr:6fcb, endpoint:1, cluster:1029, attribute:0, status:0, data_type:33, size:2, data:7065, rssi:93
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'7a6fcb01040300000029000203f5'
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:122, addr:6fcb, endpoint:1, cluster:1027, attribute:0, status:0, data_type:41, size:2, data:1013, rssi:93
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'7a6fcb010403001400280001ff'
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:122, addr:6fcb, endpoint:1, cluster:1027, attribute:20, status:0, data_type:40, size:1, data:-1, rssi:93
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'7a6fcb0104030010002900022796'
2018-12-22 14:52:13 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:122, addr:6fcb, endpoint:1, cluster:1027, attribute:16, status:0, data_type:41, size:2, data:10134, rssi:93
2018-12-22 14:53:10 DEBUG (Thread-288) [zigate] Auto saving /config/zigate.json
2018-12-22 14:53:10 DEBUG (Thread-288) [zigate] Saving persistent file
2018-12-22 14:53:10 DEBUG (Thread-288) [zigate] File already existing, make a backup before
2018-12-22 14:55:44 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x81\x02\x12\x02\x102\x02\x16{o\xcb\x02\x11\x02\x10\x02\x10\xff\x02\x11\x02\x10B\x02\x10%\x02\x11!\xbd\x02\x1b\x02\x14!\xa8\x13\x02\x15!\x02\x1b\x02\x10\x02\x16$\x02\x11\x02\x10\x02\x10\x02\x10\x02\x10d)\x19\x02\x18e!\x98\x1bf+\xde\x8b\x02\x11\x02\x10\x02\x1a!\x02\x10\x02\x10]\x03'
2018-12-22 14:55:44 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 14:55:44 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'7b6fcb010000ff01004200250121bd0b0421a81305210b0006240100000000642919086521981b662bde8b01000a210000'
2018-12-22 14:55:44 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:123, addr:6fcb, endpoint:1, cluster:0, attribute:65281, status:0, data_type:66, size:37, data:0121bd0b0421a81305210b0006240100000000642919086521981b662bde8b01000a210000, rssi:93
2018-12-22 14:55:44 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 14:55:44 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 14:56:31 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b"\x01\x81\x02\x12\x02\x10\x02\x1ffq{\x02\x1e\x02\x11\x02\x14\x02\x12\x02\x10\x02\x10\x02\x10)\x02\x10\x02\x12\x02\x17H\x8d\x03\x01\x81\x02\x12\x02\x10\x02\x1f\x02\x17r{\x02\x1e\x02\x11\x02\x14\x02\x15\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x12\x1e<\x8d\x03\x01\x81\x02\x12\x02\x10\x02\x1f\xdcs{\x02\x1e\x02\x11\x02\x14\x02\x13\x02\x10\x02\x10\x02\x10)\x02\x10\x02\x12\x02\x13\xf5\x8d\x03\x01\x81\x02\x12\x02\x10\x02\x1e\xc2s{\x02\x1e\x02\x11\x02\x14\x02\x13\x02\x10\x14\x02\x10(\x02\x10\x02\x11\xff\x8d\x03\x01\x81\x02\x12\x02\x10\x02\x1f\x8fs{\x02\x1e\x02\x11\x02\x14\x02\x13\x02\x10\x10\x02\x10)\x02\x10\x02\x12'\x92\x8d\x03"
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'717b0e0104020000002900020748'
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:113, addr:7b0e, endpoint:1, cluster:1026, attribute:0, status:0, data_type:41, size:2, data:1864, rssi:141
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_ATTRIBUTE_UPDATED
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'727b0e0104050000002100021e3c'
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:114, addr:7b0e, endpoint:1, cluster:1029, attribute:0, status:0, data_type:33, size:2, data:7740, rssi:141
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'737b0e01040300000029000203f5'
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:115, addr:7b0e, endpoint:1, cluster:1027, attribute:0, status:0, data_type:41, size:2, data:1013, rssi:141
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'737b0e010403001400280001ff'
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:115, addr:7b0e, endpoint:1, cluster:1027, attribute:20, status:0, data_type:40, size:1, data:-1, rssi:141
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] Received response 0x8102: b'737b0e0104030010002900022792'
2018-12-22 14:56:31 DEBUG (ZiGate-Event Loop) [zigate] RESPONSE 0x8102 - Individual Attribute Report : sequence:115, addr:7b0e, endpoint:1, cluster:1027, attribute:16, status:0, data_type:41, size:2, data:10130, rssi:141
2018-12-22 14:58:10 DEBUG (Thread-289) [zigate] Auto saving /config/zigate.json
2018-12-22 14:58:10 DEBUG (Thread-289) [zigate] Saving persistent file
2018-12-22 14:58:10 DEBUG (Thread-289) [zigate] File already existing, make a backup before
2018-12-22 15:00:02 ERROR (MainThread) [homeassistant.core] Error doing job: SSL handshake failed
Traceback (most recent call last):
  File "uvloop/handles/stream.pyx", line 609, in uvloop.loop.UVStream._on_eof
  File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata
  File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake
    self._sslobj.do_handshake()
ssl.SSLError: [SSL: UNKNOWN_PROTOCOL] unknown protocol (_ssl.c:841)
2018-12-22 15:00:02 ERROR (MainThread) [homeassistant.core] Error doing job: SSL error errno:1 reason: UNKNOWN_PROTOCOL
Traceback (most recent call last):
  File "uvloop/sslproto.pyx", line 504, in uvloop.loop.SSLProtocol.data_received
  File "uvloop/sslproto.pyx", line 204, in uvloop.loop._SSLPipe.feed_ssldata
  File "uvloop/sslproto.pyx", line 171, in uvloop.loop._SSLPipe.feed_ssldata
  File "/usr/local/lib/python3.6/ssl.py", line 689, in do_handshake
    self._sslobj.do_handshake()
ssl.SSLError: [SSL: UNKNOWN_PROTOCOL] unknown protocol (_ssl.c:841)
2018-12-22 15:03:10 DEBUG (Thread-290) [zigate] Auto saving /config/zigate.json
2018-12-22 15:03:10 DEBUG (Thread-290) [zigate] Saving persistent file
2018-12-22 15:03:10 DEBUG (Thread-290) [zigate] File already existing, make a backup before
2018-12-22 15:08:10 DEBUG (Thread-291) [zigate] Auto saving /config/zigate.json
2018-12-22 15:08:10 DEBUG (Thread-291) [zigate] Saving persistent file
2018-12-22 15:08:10 DEBUG (Thread-291) [zigate] File already existing, make a backup before
2018-12-22 15:13:10 DEBUG (Thread-292) [zigate] Auto saving /config/zigate.json
2018-12-22 15:13:11 DEBUG (Thread-292) [zigate] Saving persistent file
2018-12-22 15:13:11 DEBUG (Thread-292) [zigate] File already existing, make a backup before
2018-12-22 15:18:11 DEBUG (Thread-293) [zigate] Auto saving /config/zigate.json
2018-12-22 15:18:11 DEBUG (Thread-293) [zigate] Saving persistent file
2018-12-22 15:18:11 DEBUG (Thread-293) [zigate] File already existing, make a backup before
2018-12-22 15:23:11 DEBUG (Thread-294) [zigate] Auto saving /config/zigate.json
2018-12-22 15:23:11 DEBUG (Thread-294) [zigate] Saving persistent file
2018-12-22 15:23:11 DEBUG (Thread-294) [zigate] File already existing, make a backup before
2018-12-22 15:28:11 DEBUG (Thread-295) [zigate] Auto saving /config/zigate.json
2018-12-22 15:28:11 DEBUG (Thread-295) [zigate] Saving persistent file
2018-12-22 15:28:11 DEBUG (Thread-295) [zigate] File already existing, make a backup before

doudz commented 5 years ago

Everything looks fine. Your sensor isn't too far ? what's the latest rssi value ?

jalme commented 5 years ago

Hi,

Today most sensors started updating except a few.

The details for one of the few not updating is:

Not using the wifi module is an issue since the range to Zigate is not perfect for some sensors. That was the reason why I installed the osram smart plugs but no difference (related to issue #18 ).

doudz commented 5 years ago

I think it's not related to HA component, or python zigate lib, it's probably a range issue or zigate firmware issue. Next week I'll try the pre-release 3.0f, it should solve the problem with XIAOMI sensors, I suppose this is the problem you encounter now

max5962 commented 5 years ago

@doudz. I'm using this new firmware for a week. No issues but the zigate feel "laggy". 20 minutes to pair 2 ikea outlet ...

doudz commented 5 years ago

20minutes it's really slow , I supposed this is the current refresh process which is really slow, badly coded. I'll change that soon

doudz commented 5 years ago

Should be fixed now, please test

jalme commented 5 years ago

Testing. Will keep you posted.

doudz commented 5 years ago

it ok now ?

jalme commented 5 years ago

No more issues. Thanks

doudz commented 5 years ago

:+1:

doudz / homeassistant-zigate

Sensors stop updating state after a few hours #15