search

doudz / homeassistant-zigate

zigate component for Home Assistant
MIT License
48 stars 13 forks source link

Status not synced for IKEA bulb and outlet #43

Closed nerdosity closed 5 years ago

nerdosity commented 5 years ago

Hi,

Yesterday I updated everything to hassos, updated zigate fw to latest version and paired all my devices. I added a new device, a little E14 Tradfri bulb for my desk lamp. But, it's not responding very well. Turning it off from HA -> lamp turn off, but status in HA quickly turn to off, then on again. I can set brightness and temperature, but cannot have its status synced. It was very difficult to had it paired, because a lot of times it just stayed as a simple unrecognized element. Only after 5/6 try, it paired.

2019-04-24 00:22:15 DEBUG (SyncWorker_2) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x00' 2019-04-24 00:22:15 DEBUG (SyncWorker_2) [zigate] Msg to send b'00920006e802d6a8010100' 2019-04-24 00:22:15 DEBUG (SyncWorker_2) [zigate] Encoded Msg to send b'0102109202100216e80212d6a802110211021003' 2019-04-24 00:22:15 DEBUG (SyncWorker_2) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:16 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15m\x02\x10z\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:16 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007a0092' 2019-04-24 00:22:16 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:122, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:16 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:16 DEBUG (SyncWorker_2) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:122, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:20 DEBUG (SyncWorker_7) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x00' 2019-04-24 00:22:20 DEBUG (SyncWorker_7) [zigate] Msg to send b'00920006e802d6a8010100' 2019-04-24 00:22:20 DEBUG (SyncWorker_7) [zigate] Encoded Msg to send b'0102109202100216e80212d6a802110211021003' 2019-04-24 00:22:20 DEBUG (SyncWorker_7) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:20 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15l\x02\x10{\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:20 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:20 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007b0092' 2019-04-24 00:22:20 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:123, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:20 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:20 DEBUG (SyncWorker_7) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:123, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:23 DEBUG (SyncWorker_19) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x00' 2019-04-24 00:22:23 DEBUG (SyncWorker_19) [zigate] Msg to send b'00920006e802d6a8010100' 2019-04-24 00:22:23 DEBUG (SyncWorker_19) [zigate] Encoded Msg to send b'0102109202100216e80212d6a802110211021003' 2019-04-24 00:22:23 DEBUG (SyncWorker_19) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:23 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15k\x02\x10|\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:23 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:23 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007c0092' 2019-04-24 00:22:23 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:124, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:23 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:23 DEBUG (SyncWorker_19) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:124, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:24 DEBUG (SyncWorker_10) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x01' 2019-04-24 00:22:24 DEBUG (SyncWorker_10) [zigate] Msg to send b'00920006e902d6a8010101' 2019-04-24 00:22:24 DEBUG (SyncWorker_10) [zigate] Encoded Msg to send b'0102109202100216e90212d6a802110211021103' 2019-04-24 00:22:24 DEBUG (SyncWorker_10) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:24 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15j\x02\x10}\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:24 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:24 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007d0092' 2019-04-24 00:22:24 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:125, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:24 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:24 DEBUG (SyncWorker_10) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:125, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:27 DEBUG (SyncWorker_11) [zigate] REQUEST : 0x0081 b'\x02\xd6\xa8\x01\x01\x01N\x00\x00' 2019-04-24 00:22:27 DEBUG (SyncWorker_11) [zigate] Msg to send b'00810009bb02d6a80101014e0000' 2019-04-24 00:22:27 DEBUG (SyncWorker_11) [zigate] Encoded Msg to send b'0102108102100219bb0212d6a80211021102114e0210021003' 2019-04-24 00:22:27 DEBUG (SyncWorker_11) [zigate] Waiting for status message for command 0x0081 2019-04-24 00:22:27 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15z\x02\x10~\x02\x10\x81\x02\x10\x03' 2019-04-24 00:22:27 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:27 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007e0081' 2019-04-24 00:22:27 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:126, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:27 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:27 DEBUG (SyncWorker_11) [zigate] STATUS code to command 0x0081:RESPONSE 0x8000 - Status response : status:0, sequence:126, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:28 DEBUG (SyncWorker_4) [zigate] REQUEST : 0x0081 b'\x02\xd6\xa8\x01\x01\x01\xa2\x00\x00' 2019-04-24 00:22:28 DEBUG (SyncWorker_4) [zigate] Msg to send b'008100095702d6a8010101a20000' 2019-04-24 00:22:28 DEBUG (SyncWorker_4) [zigate] Encoded Msg to send b'0102108102100219570212d6a8021102110211a20210021003' 2019-04-24 00:22:28 DEBUG (SyncWorker_4) [zigate] Waiting for status message for command 0x0081 2019-04-24 00:22:28 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15{\x02\x10\x7f\x02\x10\x81\x02\x10\x03' 2019-04-24 00:22:28 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:28 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'007f0081' 2019-04-24 00:22:28 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:127, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:28 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:28 DEBUG (SyncWorker_4) [zigate] STATUS code to command 0x0081:RESPONSE 0x8000 - Status response : status:0, sequence:127, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:30 DEBUG (SyncWorker_6) [zigate] REQUEST : 0x0081 b'\x02\xd6\xa8\x01\x01\x01\xf1\x00\x00' 2019-04-24 00:22:30 DEBUG (SyncWorker_6) [zigate] Msg to send b'008100090402d6a8010101f10000' 2019-04-24 00:22:30 DEBUG (SyncWorker_6) [zigate] Encoded Msg to send b'010210810210021902140212d6a8021102110211f10210021003' 2019-04-24 00:22:30 DEBUG (SyncWorker_6) [zigate] Waiting for status message for command 0x0081 2019-04-24 00:22:30 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\x84\x02\x10\x80\x02\x10\x81\x02\x10\x03' 2019-04-24 00:22:30 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:30 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00800081' 2019-04-24 00:22:30 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:128, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:30 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:30 DEBUG (SyncWorker_6) [zigate] STATUS code to command 0x0081:RESPONSE 0x8000 - Status response : status:0, sequence:128, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:30 DEBUG (SyncWorker_0) [zigate] REQUEST : 0x0081 b'\x02\xd6\xa8\x01\x01\x01\xfe\x00\x00' 2019-04-24 00:22:30 DEBUG (SyncWorker_0) [zigate] Msg to send b'008100090b02d6a8010101fe0000' 2019-04-24 00:22:30 DEBUG (SyncWorker_0) [zigate] Encoded Msg to send b'0102108102100219021b0212d6a8021102110211fe0210021003' 2019-04-24 00:22:30 DEBUG (SyncWorker_0) [zigate] Waiting for status message for command 0x0081 2019-04-24 00:22:31 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\x85\x02\x10\x81\x02\x10\x81\x02\x10\x03' 2019-04-24 00:22:31 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:31 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00810081' 2019-04-24 00:22:31 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:129, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:31 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:31 DEBUG (SyncWorker_0) [zigate] STATUS code to command 0x0081:RESPONSE 0x8000 - Status response : status:0, sequence:129, packet_type:129, error:b'', lqi:0 2019-04-24 00:22:32 DEBUG (SyncWorker_18) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x01' 2019-04-24 00:22:32 DEBUG (SyncWorker_18) [zigate] Msg to send b'00920006e902d6a8010101' 2019-04-24 00:22:32 DEBUG (SyncWorker_18) [zigate] Encoded Msg to send b'0102109202100216e90212d6a802110211021103' 2019-04-24 00:22:32 DEBUG (SyncWorker_18) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:33 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\x95\x02\x10\x82\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:33 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00820092' 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:130, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:130, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] REQUEST : 0x00c0 b'\x02\xd6\xa8\x01\x01\x01\x0e\x00\x00' 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] Msg to send b'00c00009ba02d6a80101010e0000' 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] Encoded Msg to send b'010210c002100219ba0212d6a8021102110211021e0210021003' 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] Waiting for status message for command 0x00c0 2019-04-24 00:22:33 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xc6\x02\x10\x83\x02\x10\xc0\x02\x10\x03' 2019-04-24 00:22:33 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'008300c0' 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:131, packet_type:192, error:b'', lqi:0 2019-04-24 00:22:33 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:33 DEBUG (SyncWorker_18) [zigate] STATUS code to command 0x00c0:RESPONSE 0x8000 - Status response : status:0, sequence:131, packet_type:192, error:b'', lqi:0 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] REQUEST : 0x0092 b'\x02\xd6\xa8\x01\x01\x01' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Msg to send b'00920006e902d6a8010101' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Encoded Msg to send b'0102109202100216e90212d6a802110211021103' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Waiting for status message for command 0x0092 2019-04-24 00:22:34 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\x93\x02\x10\x84\x02\x10\x92\x02\x10\x03' 2019-04-24 00:22:34 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00840092' 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:132, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:132, packet_type:146, error:b'', lqi:0 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] REQUEST : 0x00c0 b'\x02\xd6\xa8\x01\x01\x01\x7f\x00\x00' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Msg to send b'00c00009cb02d6a80101017f0000' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Encoded Msg to send b'010210c002100219cb0212d6a80211021102117f0210021003' 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] Waiting for status message for command 0x00c0 2019-04-24 00:22:34 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xc0\x02\x10\x85\x02\x10\xc0\x02\x10\x03' 2019-04-24 00:22:34 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'008500c0' 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:133, packet_type:192, error:b'', lqi:0 2019-04-24 00:22:34 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-24 00:22:34 DEBUG (SyncWorker_15) [zigate] STATUS code to command 0x00c0:RESPONSE 0x8000 - Status response : status:0, sequence:133, packet_type:192, error:b'', lqi:0

nerdosity commented 5 years ago

It solved itself when I removed the installation based on Hass.os and I went back to a bare metal installation on a Raspberry Pi 3 B+ Just doing this all my lamp are now working good, new and old ones.

nerdosity commented 5 years ago

It happened again. Reporting this in Zigate GitHub too.

nerdosity commented 5 years ago

This is happening for one of the two outlets I just bought. One is working perfectly, the other one is turning on / off but the switch status in Home Assistant is not synced.

2019-04-27 20:20:11 DEBUG (Thread-18) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x01' 2019-04-27 20:20:11 DEBUG (Thread-18) [zigate] Msg to send b'009200069f025951010101' 2019-04-27 20:20:11 DEBUG (Thread-18) [zigate] Encoded Msg to send b'01021092021002169f0212595102110211021103' 2019-04-27 20:20:11 DEBUG (Thread-18) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:11 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf6\x02\x10\xe1\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:11 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:11 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e10092' 2019-04-27 20:20:11 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:225, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:11 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:11 DEBUG (Thread-18) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:225, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:14 DEBUG (Thread-14) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x01' 2019-04-27 20:20:14 DEBUG (Thread-14) [zigate] Msg to send b'009200069f025951010101' 2019-04-27 20:20:14 DEBUG (Thread-14) [zigate] Encoded Msg to send b'01021092021002169f0212595102110211021103' 2019-04-27 20:20:14 DEBUG (Thread-14) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:14 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf5\x02\x10\xe2\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:14 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:14 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e20092' 2019-04-27 20:20:14 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:226, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:14 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:14 DEBUG (Thread-14) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:226, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:15 DEBUG (Thread-3) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x00' 2019-04-27 20:20:15 DEBUG (Thread-3) [zigate] Msg to send b'009200069e025951010100' 2019-04-27 20:20:15 DEBUG (Thread-3) [zigate] Encoded Msg to send b'01021092021002169e0212595102110211021003' 2019-04-27 20:20:15 DEBUG (Thread-3) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:15 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf4\x02\x10\xe3\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:15 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:15 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e30092' 2019-04-27 20:20:15 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:227, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:15 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:15 DEBUG (Thread-3) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:227, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:16 DEBUG (Thread-5) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x01' 2019-04-27 20:20:16 DEBUG (Thread-5) [zigate] Msg to send b'009200069f025951010101' 2019-04-27 20:20:16 DEBUG (Thread-5) [zigate] Encoded Msg to send b'01021092021002169f0212595102110211021103' 2019-04-27 20:20:16 DEBUG (Thread-5) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:16 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf3\x02\x10\xe4\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:16 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:16 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e40092' 2019-04-27 20:20:16 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:228, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:16 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:16 DEBUG (Thread-5) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:228, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:17 DEBUG (Thread-4) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x01' 2019-04-27 20:20:17 DEBUG (Thread-4) [zigate] Msg to send b'009200069f025951010101' 2019-04-27 20:20:17 DEBUG (Thread-4) [zigate] Encoded Msg to send b'01021092021002169f0212595102110211021103' 2019-04-27 20:20:17 DEBUG (Thread-4) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:17 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf2\x02\x10\xe5\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:18 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:18 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e50092' 2019-04-27 20:20:18 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:229, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:18 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:18 DEBUG (Thread-4) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:229, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:19 DEBUG (Thread-7) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x01' 2019-04-27 20:20:19 DEBUG (Thread-7) [zigate] Msg to send b'009200069f025951010101' 2019-04-27 20:20:19 DEBUG (Thread-7) [zigate] Encoded Msg to send b'01021092021002169f0212595102110211021103' 2019-04-27 20:20:19 DEBUG (Thread-7) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:19 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf1\x02\x10\xe6\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:19 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e60092' 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:230, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:19 DEBUG (Thread-7) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:230, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:19 DEBUG (Thread-2) [zigate] REQUEST : 0x0092 b'\x02YQ\x01\x01\x00' 2019-04-27 20:20:19 DEBUG (Thread-2) [zigate] Msg to send b'009200069e025951010100' 2019-04-27 20:20:19 DEBUG (Thread-2) [zigate] Encoded Msg to send b'01021092021002169e0212595102110211021003' 2019-04-27 20:20:19 DEBUG (Thread-2) [zigate] Waiting for status message for command 0x0092 2019-04-27 20:20:19 DEBUG (ZiGate-Listen) [zigate] Raw packet received, b'\x01\x80\x02\x10\x02\x10\x02\x15\xf0\x02\x10\xe7\x02\x10\x92\x02\x10\x03' 2019-04-27 20:20:19 DEBUG (ZiGate-Event Loop) [zigate] Dispatch ZIGATE_PACKET_RECEIVED 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] Received response 0x8000: b'00e70092' 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] RESPONSE 0x8000 - Status response : status:0, sequence:231, packet_type:146, error:b'', lqi:0 2019-04-27 20:20:19 DEBUG (ZiGate-Decode data) [zigate] Dispatch ZIGATE_RESPONSE_RECEIVED 2019-04-27 20:20:19 DEBUG (Thread-2) [zigate] STATUS code to command 0x0092:RESPONSE 0x8000 - Status response : status:0, sequence:231, packet_type:146, error:b'', lqi:0

ruimarinho commented 5 years ago

I'm experiencing the same issue following an update of the component.

With an "IKEA of Sweden TRADFRI bulb E27 WS clear 950lm", the control works but the on/off status of the light is not synced.

biau commented 5 years ago

Have you tried this ? https://github.com/doudz/homeassistant-zigate/issues/46#issuecomment-488827263

nerdosity commented 5 years ago

These days is really going mad. Now it happens that even refresh/rediscover devices doesn't fix them anymore, and I need a full homeassistant reboot to fix sync status.

No response after command 0x0140 (119) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0100 (118) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (117) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0100 (116) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (115) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0100 (114) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0120 (113) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0092 (112) 9:23 custom_components/zigate/light.py (WARNING) No response after command 0x0100 (111) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0092 (110) 9:23 custom_components/zigate/light.py (WARNING) No response after command 0x0140 (109) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0030 (108) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0100 (107) 9:23 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (106) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0120 (105) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0100 (104) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (103) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0030 (102) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (101) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0120 (100) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (99) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0030 (98) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (97) 9:22 custom_components/zigate/init.py (WARNING) No template found for TRADFRI_bulb_E14_WS_opal_400lm 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (96) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0140 (95) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0120 (94) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0030 (93) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0092 (92) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (91) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (90) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0120 (89) 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0030 (88) 9:22 custom_components/zigate/init.py (WARNING) No template found for TRADFRI_bulb_E27_opal_1000lm 9:22 custom_components/zigate/init.py (WARNING) No response after command 0x0092 (87) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (86) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (85) 9:22 custom_components/zigate/switch.py (WARNING) No response after command 0x0092 (84) 9:22 custom_components/zigate/switch.py (WARNING) No response after command 0x0092 (83) 9:22 custom_components/zigate/switch.py (WARNING) No response after command 0x0092 (82) 9:22 custom_components/zigate/switch.py (WARNING) No response after command 0x0092 (81) 9:22 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (80) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (79) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (78) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (77) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (76) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (75) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (74) 9:21 custom_components/zigate/light.py (WARNING) No response after command 0x0092 (73) 9:21 custom_components/zigate/light.py (WARNING)

doudz commented 5 years ago

A full restart fix the problem ? if it's right, it looks like a zigate or plugin hang could you provide full debug log ?

nerdosity commented 5 years ago

A full restart fix the problem "can't sync anymore, even with device refresh+discover". But even after that, I need to resync the lights that weren't synced anymore by calling refresh+discovery.

They are doing some fixing outside this building, and sometimes they have to disconnect power. I have a battery UPS on the raspberry3 and a 12v ups on router, so it's not impacting my connection, but lights these days are going crazy.

biau commented 5 years ago

If your light lose power they turn on when power is restored. But if hass is expecting them as off then you can only change brightness until you call discover and then refresh.

nerdosity commented 5 years ago

I will provide a full debug log, it was less verbose than usual since I am starting to make a little of order in HA

benjR commented 5 years ago

I have the same issue with the outlet.

biau commented 5 years ago

I never had this issue randomly with tradfri devices only with osram smart plug. I'm wondering if it could be an incompatibility between some router and end devices.

pdecat commented 5 years ago

I've had this issue with Tradfri bulbs, but never with Osram devices (plugs and lights).

nerdosity commented 5 years ago

Well, we can say that this issue is pretty common then 😆😆😆😆

doudz commented 5 years ago

I have no problem with my OSRAM Bulb For tradfri bulbs, I have one, but not installed yet... I need to check

nerdosity commented 5 years ago

It seems that, at least for lights and outlets, this plugins reached full maturity. Yesterday light were interrupted in all neighborhood a bit of times, it's storm time in Italy, and I never had to resync / rediscover lights. And lights were always snappy, not slow as it happened in the past. For me it can be closed.

doudz commented 5 years ago

Thank for feedback