J-N-K
commented
2 years ago Is this for all devices or only those that have a manually configured IP?
Closed BigGeorgeTx closed 2 years ago
J-N-K
commented
2 years ago Is this for all devices or only those that have a manually configured IP?
BigGeorgeTx
commented
2 years ago All devices.
J-N-K
commented
2 years ago I just tried to reproduce that. I switched off the WiFi of my MacBook and the device changed to OFFLINE (as expected). However some seconds after I switched it back on, the device came back. Is the IP of the openHAB system changing when you reboot your router?
BigGeorgeTx
commented
2 years ago While I didn't specifically check it, I don't think so. My router typically leases IP addresses for at least a month. The router is a Nokia BGW320-505 provided by AT&T Uverse (Fiber).
(I reread the question. You asked about the OpenHAB system while I answered about the Tuya device. The answer is the same - the IP address stays the same. The Pi4B running openhabian keeps the same IP address.)
On Fri, Mar 11, 2022 at 10:36 AM J-N-K @.***> wrote:
I just tried to reproduce that. I switched off the WiFi of my MacBook and the device changed to OFFLINE (as expected). However some seconds after I switched it back on, the device came back. Is the IP of the openHAB system changing when you reboot your router?
— Reply to this email directly, view it on GitHub https://github.com/smarthomej/addons/issues/341#issuecomment-1065226303, or unsubscribe https://github.com/notifications/unsubscribe-auth/ADEQZL2HMBH4D4D2AYS7IUDU7NSARANCNFSM5QKBVVDA . Triage notifications on the go with GitHub Mobile for iOS https://apps.apple.com/app/apple-store/id1477376905?ct=notification-email&mt=8&pt=524675 or Android https://play.google.com/store/apps/details?id=com.github.android&referrer=utm_campaign%3Dnotification-email%26utm_medium%3Demail%26utm_source%3Dgithub.
You are receiving this because you authored the thread.Message ID: @.***>
-- George Hyde Mobile +1-281-620-3039
BigGeorgeTx
commented
2 years ago I have observed some devices for a while, and they appear to be keeping the same IP address.
Today I observed two Things/devices Offline in the UI. (The ~10 other Tuya Things are Online and functioning as expected). The first was on a circuit that had tripped its circuit breaker. It came back Online with a disable/enable cycle. I didn’t think to change to log level until after I did it, so it doesn’t give you much to go on.
2022-03-18 16:50:57.905 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from OFFLINE to UNINITIALIZED 2022-03-18 16:50:57.926 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from UNINITIALIZED to UNINITIALIZED (DISABLED) ==> /var/log/openhab/openhab.log <== 2022-03-18 16:51:03.076 [INFO ] [a.internal.handler.TuyaDeviceHandler] - Configuring IP address 'DeviceInfo{ip='192.168.1.229', version='3.1'}' for thing 'tuya:tuyaDevice:42434288807d3a3734e6'. 2022-03-18 16:51:03.098 [WARN ] [inding.tuya.internal.util.CryptoUtil] - Decryption of MQ failed: Input length not multiple of 16 bytes ==> /var/log/openhab/events.log <== 2022-03-18 16:51:03.064 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from UNINITIALIZED (DISABLED) to INITIALIZING 2022-03-18 16:51:03.078 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from INITIALIZING to UNKNOWN 2022-03-18 16:51:03.095 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from UNKNOWN to ONLINE
I changed the log level to TRACE before cycling the 2nd device. That device I have noticed falling offline for no apparent reason. It stays online and is responsive to commands through the Tuya SmartLife App. Looking at the TRACE log, it appears to be responding to heartbeat checks while it is offline. Here is the log filtered by IP address, showing activity before and after the time stamp on the cycle (which follows).
2022-03-18 17:02:12.186 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:12.188 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:17.186 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:17.189 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:22.192 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:22.194 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:27.199 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:27.201 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:32.201 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:32.203 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:37.213 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:37.215 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:42.219 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:42.221 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:42.831 [INFO ] [a.internal.handler.TuyaDeviceHandler] - Configuring IP address 'DeviceInfo{ip='192.168.1.226', version='3.3'}' for thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight'. 2022-03-18 17:02:43.037 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending DP_QUERY, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647640963, dps=null, gwId=ebb442e79ea9c02fb35xs8} 2022-03-18 17:02:43.039 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000010000000A00000088989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB196EE0C3A27A9BAF3A37FC48C025736E77F1B2D609EAC5C7A1D79CEFAEC007DB1DBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0D184472F0000AA55' 2022-03-18 17:02:43.042 [DEBUG] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. 2022-03-18 17:02:43.075 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA000000010000000A0000008C00000000C6C32363C90E0310593B0AB305AE079611EA66617D5ACCDED809B0D8BC90B0A28E9C52AE1B6A29CE26D25C985F30CFE1BE2902B3D8AF01EB131228EE634524823A6C9E3458CE1B9C335BDEC0E210B80D5AB2A02FADFABD6E2F35390472F163C143E53A3D6C4307E2D5CF2E597728E6B282E5C316B98B56AC9702D5721BC1FFFD1016ED680000AA55' 2022-03-18 17:02:43.077 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: {"dps":{"20":false,"21":"colour","22":250,"23":220,"24":"007803e803e8","25":"000e0d0000000000000000c80000","26":0}} 2022-03-18 17:02:43.078 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=DP_QUERY, content='{20=false, 21=colour, 22=250.0, 23=220.0, 24=007803e803e8, 25=000e0d0000000000000000c80000, 26=0.0}'} 2022-03-18 17:02:47.213 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:47.215 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:52.217 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:52.219 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:53.046 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending ping 2022-03-18 17:02:53.048 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending HEART_BEAT, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647640973, dps=, gwId=ebb442e79ea9c02fb35xs8} 2022-03-18 17:02:53.050 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000020000000900000097332E33000000000000000000000000989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1B104FFBD984F60F026A353697603E58361F63C7DFED8F463F23B1DF6A79777BDDBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0BADC1CFF0000AA55' 2022-03-18 17:02:53.109 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:53.111 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: 2022-03-18 17:02:53.112 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:53.113 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received pong 2022-03-18 17:02:57.223 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:57.225 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:03:02.222 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:03:02.224 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:03:03.053 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending ping 2022-03-18 17:03:03.055 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending HEART_BEAT, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647640983, dps=, gwId=ebb442e79ea9c02fb35xs8} 2022-03-18 17:03:03.057 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000030000000900000097332E33000000000000000000000000989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1DC37E8CC440335C84837E32631F8BB8661F63C7DFED8F463F23B1DF6A79777BDDBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0723E9CD80000AA55' 2022-03-18 17:03:03.144 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:03:03.145 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: 2022-03-18 17:03:03.147 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:03:03.148 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received pong 2022-03-18 17:03:07.223 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:03:07.226 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'}
Here is the TRACE log without a filter capturing the disable/enable and return to Online cycle:
2022-03-18 17:02:42.831 [INFO ] [a.internal.handler.TuyaDeviceHandler] - Configuring IP address 'DeviceInfo{ip='192.168.1.226', version='3.3'}' for thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight'. ==> /var/log/openhab/events.log <== 2022-03-18 17:02:42.787 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from UNINITIALIZED (DISABLED) to INITIALIZING 2022-03-18 17:02:42.832 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from INITIALIZING to OFFLINE (CONFIGURATION_PENDING): Waiting for IP address 2022-03-18 17:02:42.837 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE (CONFIGURATION_PENDING): Waiting for IP address to UNKNOWN ==> /var/log/openhab/openhab.log <== 2022-03-18 17:02:43.037 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending DP_QUERY, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647640963, dps=null, gwId=ebb442e79ea9c02fb35xs8} 2022-03-18 17:02:43.039 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000010000000A00000088989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB196EE0C3A27A9BAF3A37FC48C025736E77F1B2D609EAC5C7A1D79CEFAEC007DB1DBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0D184472F0000AA55' 2022-03-18 17:02:43.042 [DEBUG] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. 2022-03-18 17:02:43.075 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA000000010000000A0000008C00000000C6C32363C90E0310593B0AB305AE079611EA66617D5ACCDED809B0D8BC90B0A28E9C52AE1B6A29CE26D25C985F30CFE1BE2902B3D8AF01EB131228EE634524823A6C9E3458CE1B9C335BDEC0E210B80D5AB2A02FADFABD6E2F35390472F163C143E53A3D6C4307E2D5CF2E597728E6B282E5C316B98B56AC9702D5721BC1FFFD1016ED680000AA55' 2022-03-18 17:02:43.077 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: {"dps":{"20":false,"21":"colour","22":250,"23":220,"24":"007803e803e8","25":"000e0d0000000000000000c80000","26":0}} 2022-03-18 17:02:43.078 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=DP_QUERY, content='{20=false, 21=colour, 22=250.0, 23=220.0, 24=007803e803e8, 25=000e0d0000000000000000c80000, 26=0.0}'} 2022-03-18 17:02:43.080 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=colour, 22=250.0, 23=220.0, 24=007803e803e8, 25=000e0d0000000000000000c80000, 26=0.0}' 2022-03-18 17:02:43.603 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD2E27C5F32F3E3F0AFC41C85D5F380E6FAAA67378A1AD8D82C8881CF61B1ED1C17CDF83527CCE8ED665CF186940C31867256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786D6580B90DE59FFECA847456AFC569A70F961BDD6290849510943F0B3DEDC720E7A23E3B2249F5ADF48A714F851AAF43999DF41830000AA55' 2022-03-18 17:02:43.607 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.206","gwId":"ebb8edc2de0ce2e480xkxw","active":2,"ablilty":0,"encrypt":true,"productKey":"opqqmfjz7y2o6y6a","version":"3.3"} 2022-03-18 17:02:43.610 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.206', deviceId='ebb8edc2de0ce2e480xkxw', active=2, ability=0, mode=0, encrypt=true, productKey='opqqmfjz7y2o6y6a', version='3.3'}'} 2022-03-18 17:02:43.622 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Sending ping 2022-03-18 17:02:43.624 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a3734e6/192.168.1.229:6668: Sending HEART_BEAT, payload {devId=42434288807d3a3734e6, uid=42434288807d3a3734e6, t=1647640963, dps=, gwId=42434288807d3a3734e6} 2022-03-18 17:02:43.626 [TRACE] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a3734e6/192.168.1.229:6668: Sending encoded '000055AA00000047000000090000007B7B226465764964223A223432343334323838383037643361333733346536222C22756964223A223432343334323838383037643361333733346536222C2274223A313634373634303936332C22647073223A22222C2267774964223A223432343334323838383037643361333733346536227D92B458F40000AA55' 2022-03-18 17:02:43.630 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6/192.168.1.229:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:43.631 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6//192.168.1.229:6668: Decoded raw payload: 2022-03-18 17:02:43.633 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6//192.168.1.229:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:43.634 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Received pong 2022-03-18 17:02:43.744 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending ping 2022-03-18 17:02:43.746 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending HEART_BEAT, payload {devId=36802558840d8e95ef5d, uid=36802558840d8e95ef5d, t=1647640963, dps=, gwId=36802558840d8e95ef5d} 2022-03-18 17:02:43.748 [TRACE] [.internal.local.handlers.TuyaEncoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending encoded '000055AA00004775000000090000007B7B226465764964223A223336383032353538383430643865393565663564222C22756964223A223336383032353538383430643865393565663564222C2274223A313634373634303936332C22647073223A22222C2267774964223A223336383032353538383430643865393565663564227DEB8C22620000AA55' 2022-03-18 17:02:43.785 [TRACE] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:43.788 [TRACE] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d//192.168.1.244:6668: Decoded raw payload: 2022-03-18 17:02:43.789 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d//192.168.1.244:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:43.791 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Received pong ==> /var/log/openhab/events.log <== 2022-03-18 17:02:43.046 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from UNKNOWN to ONLINE 2022-03-18 17:02:43.089 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'FrontEntrySmartLightTuya_FrontEntrySmartlightOnOffSwitchTuya' changed from ON to OFF ==> /var/log/openhab/openhab.log <== 2022-03-18 17:02:44.040 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-18 17:02:44.042 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-18 17:02:44.163 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AFC41B78182A5D63EE2972DC4C24AF26DEB231811511651D6030733901EC3C44AF3AFEDCA50D58DBC2B584825DCE135647256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4392877DE100000AA55' 2022-03-18 17:02:44.165 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.148","gwId":"ebf3cb07f3574a9688z4oy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-18 17:02:44.166 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.148', deviceId='ebf3cb07f3574a9688z4oy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-18 17:02:44.215 [TRACE] [rnal.local.handlers.HeartbeatHandler] - eb349b6012eb05c13103yh/192.168.1.236:6668: Sending ping 2022-03-18 17:02:44.216 [DEBUG] [.internal.local.handlers.TuyaEncoder] - eb349b6012eb05c13103yh/192.168.1.236:6668: Sending HEART_BEAT, payload {devId=eb349b6012eb05c13103yh, uid=eb349b6012eb05c13103yh, t=1647640964, dps=, gwId=eb349b6012eb05c13103yh} 2022-03-18 17:02:44.219 [TRACE] [.internal.local.handlers.TuyaEncoder] - eb349b6012eb05c13103yh/192.168.1.236:6668: Sending encoded '000055AA0000476A0000000900000097332E33000000000000000000000000FF499C50E5725F656C694A7338ECCEE57C6B9B9F4F41B6F5F76DB9554ED7DD942B2F8372E38A6F0356736E58DDE72D1921AEC935B28853B4CE448F6FF6BC6816BA1313752C1B2C3041DA3A61043EAE9C6BCB9E1DB7127F7FFC979E0FD4A9D5D217B69B1E999470CD6A5AF7B10FBDBBD2BC6FA21163ECF640F7BB6A027484B82EC870EFB80000AA55' 2022-03-18 17:02:44.304 [TRACE] [.internal.local.handlers.TuyaDecoder] - eb349b6012eb05c13103yh/192.168.1.236:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:44.306 [TRACE] [.internal.local.handlers.TuyaDecoder] - eb349b6012eb05c13103yh//192.168.1.236:6668: Decoded raw payload: 2022-03-18 17:02:44.308 [DEBUG] [.internal.local.handlers.TuyaDecoder] - eb349b6012eb05c13103yh//192.168.1.236:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:44.311 [TRACE] [rnal.local.handlers.HeartbeatHandler] - eb349b6012eb05c13103yh/192.168.1.236:6668: Received pong 2022-03-18 17:02:44.325 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a372fd5/192.168.1.245:6668: Sending ping 2022-03-18 17:02:44.327 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a372fd5/192.168.1.245:6668: Sending HEART_BEAT, payload {devId=42434288807d3a372fd5, uid=42434288807d3a372fd5, t=1647640964, dps=, gwId=42434288807d3a372fd5} 2022-03-18 17:02:44.329 [TRACE] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a372fd5/192.168.1.245:6668: Sending encoded '000055AA0000476E000000090000007B7B226465764964223A223432343334323838383037643361333732666435222C22756964223A223432343334323838383037643361333732666435222C2274223A313634373634303936342C22647073223A22222C2267774964223A223432343334323838383037643361333732666435227DA5B0507D0000AA55' 2022-03-18 17:02:44.330 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 21410372c44f33b896bf/192.168.1.227:6668: Sending ping 2022-03-18 17:02:44.332 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 21410372c44f33b896bf/192.168.1.227:6668: Sending HEART_BEAT, payload {devId=21410372c44f33b896bf, uid=21410372c44f33b896bf, t=1647640964, dps=, gwId=21410372c44f33b896bf} 2022-03-18 17:02:44.333 [TRACE] [.internal.local.handlers.TuyaEncoder] - 21410372c44f33b896bf/192.168.1.227:6668: Sending encoded '000055AA0000476E0000000900000097332E330000000000000000000000006EEA753E1E903275D77483DA455AB45A98EE425267A06575E5C49CE6D9B3F5CB1005D68967C37D2351A4DFA03B6F9607D303FF7463E04A5AC97DE757BC5F62B59060D8E7CC54A6838867068E42EEA27C75363F6449E12DE469A72743A413B00139D718CA131809CCD812CA7872EE04B5E230326D419C20CB00CC8AF5A8BC4418466B90B40000AA55' 2022-03-18 17:02:44.335 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a372fd5/192.168.1.245:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:44.337 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a372fd5//192.168.1.245:6668: Decoded raw payload: 2022-03-18 17:02:44.338 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a372fd5//192.168.1.245:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:44.340 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a372fd5/192.168.1.245:6668: Received pong 2022-03-18 17:02:44.342 [TRACE] [.internal.local.handlers.TuyaDecoder] - 21410372c44f33b896bf/192.168.1.227:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-18 17:02:44.343 [TRACE] [.internal.local.handlers.TuyaDecoder] - 21410372c44f33b896bf//192.168.1.227:6668: Decoded raw payload: 2022-03-18 17:02:44.345 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 21410372c44f33b896bf//192.168.1.227:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-18 17:02:44.347 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 21410372c44f33b896bf/192.168.1.227:6668: Received pong 2022-03-18 17:02:44.687 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD8F08E1A21472AEB7C7EA6D979A919E00A6000871662789E023EEDBAC9EBFB51C24B7C6CB6C84FC4998BA77D267A7EEA7256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4397D9948220000AA55' 2022-03-18 17:02:44.689 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.208","gwId":"eb04ec86abf62e08b1932f","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-18 17:02:44.691 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.208', deviceId='eb04ec86abf62e08b1932f', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-18 17:02:44.859 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000CC00000000D09766676F3369EB10B5E9F132FD802AE33F0A22D663644012FC8D872BCF03D07B64C8DD4401899000716D5170D1858B0B1B576FA1E157538A210ED25F4C31067256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE57978641CBF9244804CF3DC911CADA0E75770803BF05AC9D8E6CCA4A3B9E5968A55B0DCC37291424EC2B5401F4F50D7EB6A63204AF131AE1CD3F0E4AB9A53DA72F98A8DE9F9AC76E33C955A8D1F62F82FAB781D482232E3BD3BF5CF00BC08816559FD69B99BF8B0000AA55' 2022-03-18 17:02:44.862 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.246","gwId":"eb755c84e755bbd761ymwj","active":2,"ablilty":0,"encrypt":true,"productKey":"keyyj3fy8x98arty","version":"3.3","lan_cap":5000,"lan_seq":26,"token":true} 2022-03-18 17:02:44.864 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.246', deviceId='eb755c84e755bbd761ymwj', active=2, ability=0, mode=0, encrypt=true, productKey='keyyj3fy8x98arty', version='3.3'}'} 2022-03-18 17:02:44.977 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A3EDB2FB5FEFF0568F68195638EDEDF92FC42CFBC87C56B05EADFF8A6A44376C01AC8F3F3C78B01106FB63E08EE964D247256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF439EB3C8AB40000AA55' 2022-03-18 17:02:44.979 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.180","gwId":"eb3c82adf880a32bbe5ivy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-18 17:02:44.982 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.180', deviceId='eb3c82adf880a32bbe5ivy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-18 17:02:47.039 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-18 17:02:47.042 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-18 17:02:47.211 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-18 17:02:47.213 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-18 17:02:47.215 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-18 17:02:47.312 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AB5CC3CB4691A94082F96F1F413ADCDFD204C082BA5001B8F04F84D572A6D06012FD49AE1EB2FED8853E642A618B5ED077256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786F4C1F700BD0A36716389950D991E16BAA2647AE2696B993AD82600478BF988287A23E3B2249F5ADF48A714F851AAF439014E4A910000AA55'
J-N-K
commented
2 years ago Unfortunately the interesting part is immediately before it goes offline, not before it goes online. Can you control the device when it's shown as offline?
I have prepared a test version in the develop repository. You'll see log entries at ERROR level, these are not really errors but debug message which I raised in severity to show them even if TRACE logging ist not available.
BigGeorgeTx
commented
2 years ago I cannot control the devices that show as off line. I will load the new binding, set the log level to ERROR, watch it and report back.
J-N-K
commented
2 years ago Don't change the log level (or set it to INFO), otherwise it'll be hard to see what happens.
BigGeorgeTx
commented
2 years ago OK. I will leave the log at TRACE.
On Mon, Mar 21, 2022 at 2:37 PM J-N-K @.***> wrote:
Don't change the log level (or set it to INFO), otherwise it'll be hard to see what happens.
— Reply to this email directly, view it on GitHub https://github.com/smarthomej/addons/issues/341#issuecomment-1074274191, or unsubscribe https://github.com/notifications/unsubscribe-auth/ADEQZL56C5BEBUMDMANSRQ3VBC6XBANCNFSM5QKBVVDA . You are receiving this because you authored the thread.Message ID: @.***>
-- George Hyde Mobile +1-281-620-3039
BigGeorgeTx
commented
2 years ago In this log, you can see what happens when the device drops Offline:
2022-03-21 14:54:30.539 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-21 14:54:30.541 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-21 14:54:30.543 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-21 14:54:31.629 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending ping 2022-03-21 14:54:31.631 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending HEART_BEAT, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647892471, dps=, gwId=ebb442e79ea9c02fb35xs8} 2022-03-21 14:54:31.633 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000040000000900000097332E33000000000000000000000000989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB123E9BB0B21ECFDCF05299F68B9123B9161F63C7DFED8F463F23B1DF6A79777BDDBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0C1BE25EE0000AA55' 2022-03-21 14:54:31.757 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD8F08E1A21472AEB7C7EA6D979A919E00A6000871662789E023EEDBAC9EBFB51C24B7C6CB6C84FC4998BA77D267A7EEA7256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4397D9948220000AA55' 2022-03-21 14:54:31.759 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.208","gwId":"eb04ec86abf62e08b1932f","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-21 14:54:31.762 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.208', deviceId='eb04ec86abf62e08b1932f', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-21 14:54:31.821 [DEBUG] [rnal.local.handlers.UserEventHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: IOException caught, closing channel. java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcherImpl.read0(Native Method) ~[?:?] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) ~[?:?] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:276) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:233) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:223) ~[?:?] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:356) ~[?:?] at io.netty.buffer.PooledByteBuf.setBytes(PooledByteBuf.java:253) ~[bundleFile:4.1.72.Final] at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1132) ~[bundleFile:4.1.72.Final] at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:350) ~[bundleFile:4.1.72.Final] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:151) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) [bundleFile:4.1.72.Final] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.72.Final] at java.lang.Thread.run(Thread.java:829) [?:?] 2022-03-21 14:54:31.826 [DEBUG] [rnal.local.handlers.UserEventHandler] - IOException caught: java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcherImpl.read0(Native Method) ~[?:?] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) ~[?:?] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:276) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:233) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:223) ~[?:?] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:356) ~[?:?] at io.netty.buffer.PooledByteBuf.setBytes(PooledByteBuf.java:253) ~[bundleFile:4.1.72.Final] at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1132) ~[bundleFile:4.1.72.Final] at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:350) ~[bundleFile:4.1.72.Final] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:151) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) [bundleFile:4.1.72.Final] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.72.Final] at java.lang.Thread.run(Thread.java:829) [?:?] 2022-03-21 14:54:31.831 [DEBUG] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. 2022-03-21 14:54:31.833 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-21 14:54:31.833 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE
Here it comes back Online, which it was not doing before, or at least I didn't see in the UI that it was. Perhaps it was just cycling to fast for the UI to update.
2022-03-21 14:54:35.308 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-21 14:54:35.309 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-21 14:54:35.310 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-21 14:54:36.254 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-21 14:54:36.256 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-21 14:54:36.835 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-21 14:54:36.935 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending DP_QUERY, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647892476, dps=null, gwId=ebb442e79ea9c02fb35xs8} 2022-03-21 14:54:36.937 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000010000000A00000088989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1C8F91C73B789F51E05BDDCAF42A5FA5F7F1B2D609EAC5C7A1D79CEFAEC007DB1DBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F00996AD4B0000AA55' 2022-03-21 14:54:36.939 [DEBUG] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. ==> /var/log/openhab/events.log <== 2022-03-21 14:54:36.942 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-21 14:54:37.050 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA000000010000000A0000008C00000000C6C32363C90E0310593B0AB305AE0796953A8182E728804EF21C3F055C56EDC5C36C279D5A5B2FED1B6204A2729D7015DFDA09224018B4CF961AAB1CC0946320C49DC3DFD1125F1192FCA361704B30C89B8B014EE616E0C4D90DEC25967B34BDC6F23EE824950ECF254F93BD1AAAEFD83BCFA59BAC41C5D5C10E45F3C517EB3B1C2CF2930000AA55' 2022-03-21 14:54:37.052 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: {"dps":{"20":false,"21":"white","22":250,"23":220,"24":"00b403e803e8","25":"000e0d0000000000000000c80000","26":0}} 2022-03-21 14:54:37.054 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=DP_QUERY, content='{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}'} 2022-03-21 14:54:37.056 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}'
And here it looks like everything worked:
2022-03-21 14:54:45.547 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-21 14:54:45.549 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-21 14:54:45.551 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-21 14:54:46.942 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending ping 2022-03-21 14:54:46.944 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending HEART_BEAT, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1647892486, dps=, gwId=ebb442e79ea9c02fb35xs8} 2022-03-21 14:54:46.947 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000020000000900000097332E33000000000000000000000000989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1D0D80BB8FE2117734B7E2C3E0CF1799861F63C7DFED8F463F23B1DF6A79777BDDBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F04C44635F0000AA55' 2022-03-21 14:54:46.972 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-21 14:54:46.973 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Decoded raw payload: 2022-03-21 14:54:46.975 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebb442e79ea9c02fb35xs8//192.168.1.226:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-21 14:54:46.977 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Received pong
Here I filtered the log by a key word "Front" that is in the Thing name:
2022-03-21 15:08:29.331 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newErrorEvent triggered 2022-03-21 15:08:27.612 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:08:32.736 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE 2022-03-21 15:08:33.147 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}' 2022-03-21 15:08:53.825 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE 2022-03-21 15:08:53.825 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:08:58.950 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE 2022-03-21 15:08:59.351 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newErrorEvent triggered 2022-03-21 15:08:53.825 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:08:59.364 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}' 2022-03-21 15:08:59.467 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{30=AAAAAAA=}' 2022-03-21 15:08:59.468 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '30' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:08:59.558 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{31=AAA=}' 2022-03-21 15:08:59.559 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '31' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:08:59.660 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{32=AAA=}' 2022-03-21 15:08:59.662 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '32' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:09:29.152 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:09:29.155 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE 2022-03-21 15:09:29.377 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newErrorEvent triggered 2022-03-21 15:09:29.152 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:09:34.271 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE 2022-03-21 15:09:34.688 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}' 2022-03-21 15:09:34.891 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{31=AAA=}' 2022-03-21 15:09:34.892 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '31' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:09:34.999 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{32=AAA=}' 2022-03-21 15:09:35.001 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '32' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:09:54.469 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:09:54.471 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE 2022-03-21 15:09:59.396 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newErrorEvent triggered 2022-03-21 15:09:54.469 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1058291, resolver: io.netty.resolver.DefaultAddressResolverGroup@1594a81) / disposing=false 2022-03-21 15:09:59.569 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE 2022-03-21 15:09:59.686 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}' 2022-03-21 15:10:01.313 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{30=AAAAAAA=}' 2022-03-21 15:10:01.315 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '30' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:10:01.416 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{31=AAA=}' 2022-03-21 15:10:01.418 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '31' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' 2022-03-21 15:10:01.528 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{32=AAA=}' 2022-03-21 15:10:01.530 [DEBUG] [a.internal.handler.TuyaDeviceHandler] - Could not find channel for dp '32' in thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight'
I would send you the entire log, but it is too big to copy and paste. If there is another way to send more, I happy to provide it.
BigGeorgeTx
commented
2 years ago I don't know if this is related to this problem or not, but this appeared by it in the log.
2022-03-22 14:27:28.809 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'MyQSpringBigDoor_GarageDoorRollershutter' changed from 100 to 0 2022-03-22 14:27:32.089 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-22 14:27:32.089 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1832a6e, resolver: io.netty.resolver.DefaultAddressResolverGroup@30560e) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-22 14:27:32.414 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'MyQSpringBigDoor_GarageDoorRollershutter' changed from 0 to 100 ==> /var/log/openhab/openhab.log <== 2022-03-22 14:27:32.567 [WARN ] [ache.cxf.phase.PhaseInterceptorChain] - Interceptor for {http://internal.id.core.openhab.org/}UUIDResource has thrown exception, unwinding now org.apache.cxf.interceptor.Fault: Could not send Message. at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:67) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:308) ~[bundleFile:3.4.5] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:90) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:308) ~[bundleFile:3.4.5] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.4.5] at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:298) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.4.5] at javax.servlet.http.HttpServlet.service(HttpServlet.java:687) ~[bundleFile:3.1.0] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:273) ~[bundleFile:3.4.5] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:550) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) ~[bundleFile:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:294) ~[bundleFile:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:82) ~[bundleFile:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:386) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.43.v20210629] at java.lang.Thread.run(Thread.java:829) [?:?] Caused by: org.eclipse.jetty.io.EofException at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:279) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:277) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:381) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:829) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:223) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:550) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:915) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:987) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpOutput.channelWrite(HttpOutput.java:285) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpOutput.close(HttpOutput.java:638) ~[bundleFile:9.4.43.v20210629] at org.apache.cxf.transport.http.AbstractHTTPDestination$WrappedOutputStream.close(AbstractHTTPDestination.java:791) ~[bundleFile:3.4.5] at org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56) ~[bundleFile:3.4.5] at org.apache.cxf.transport.http.AbstractHTTPDestination$BackChannelConduit.close(AbstractHTTPDestination.java:722) ~[bundleFile:3.4.5] at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:63) ~[bundleFile:3.4.5] ... 48 more Caused by: java.io.IOException: Broken pipe at sun.nio.ch.FileDispatcherImpl.writev0(Native Method) ~[?:?] at sun.nio.ch.SocketDispatcher.writev(SocketDispatcher.java:51) ~[?:?] at sun.nio.ch.IOUtil.write(IOUtil.java:182) ~[?:?] at sun.nio.ch.IOUtil.write(IOUtil.java:130) ~[?:?] at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:493) ~[?:?] at java.nio.channels.SocketChannel.write(SocketChannel.java:507) ~[?:?] at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:273) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:422) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.WriteFlusher.write(WriteFlusher.java:277) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.AbstractEndPoint.write(AbstractEndPoint.java:381) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection$SendCallback.process(HttpConnection.java:829) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.IteratingCallback.processing(IteratingCallback.java:241) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.IteratingCallback.iterate(IteratingCallback.java:223) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection.send(HttpConnection.java:550) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.sendResponse(HttpChannel.java:915) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.write(HttpChannel.java:987) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpOutput.channelWrite(HttpOutput.java:285) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpOutput.close(HttpOutput.java:638) ~[bundleFile:9.4.43.v20210629] at org.apache.cxf.transport.http.AbstractHTTPDestination$WrappedOutputStream.close(AbstractHTTPDestination.java:791) ~[bundleFile:3.4.5] at org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56) ~[bundleFile:3.4.5] at org.apache.cxf.transport.http.AbstractHTTPDestination$BackChannelConduit.close(AbstractHTTPDestination.java:722) ~[bundleFile:3.4.5] at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:63) ~[bundleFile:3.4.5] ... 48 more 2022-03-22 14:27:32.580 [WARN ] [ache.cxf.phase.PhaseInterceptorChain] - Interceptor for {http://internal.id.core.openhab.org/}UUIDResource has thrown exception, unwinding now org.apache.cxf.interceptor.Fault: XML_WRITE_EXC at org.apache.cxf.jaxrs.interceptor.JAXRSDefaultFaultOutInterceptor.handleMessage(JAXRSDefaultFaultOutInterceptor.java:106) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:308) ~[bundleFile:3.4.5] at org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(AbstractFaultChainInitiatorObserver.java:112) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.wrapExceptionAsFault(PhaseInterceptorChain.java:374) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:332) ~[bundleFile:3.4.5] at org.apache.cxf.interceptor.OutgoingChainInterceptor.handleMessage(OutgoingChainInterceptor.java:90) ~[bundleFile:3.4.5] at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:308) ~[bundleFile:3.4.5] at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.4.5] at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:298) ~[bundleFile:3.4.5] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.4.5] at javax.servlet.http.HttpServlet.service(HttpServlet.java:687) ~[bundleFile:3.1.0] at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:273) ~[bundleFile:3.4.5] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:550) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) ~[bundleFile:?] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:294) ~[bundleFile:?] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.43.v20210629] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:82) ~[bundleFile:?] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:386) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.43.v20210629] at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.43.v20210629] at java.lang.Thread.run(Thread.java:829) [?:?] Caused by: com.ctc.wstx.exc.WstxIOException: Closed at com.ctc.wstx.sw.BaseStreamWriter.flush(BaseStreamWriter.java:262) ~[?:?] at org.apache.cxf.jaxrs.interceptor.JAXRSDefaultFaultOutInterceptor.handleMessage(JAXRSDefaultFaultOutInterceptor.java:104) ~[bundleFile:3.4.5] ... 51 more Caused by: org.eclipse.jetty.io.EofException: Closed at org.eclipse.jetty.server.HttpOutput.checkWritable(HttpOutput.java:769) ~[bundleFile:9.4.43.v20210629] at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:793) ~[bundleFile:9.4.43.v20210629] at org.apache.cxf.io.AbstractWrappedOutputStream.write(AbstractWrappedOutputStream.java:51) ~[bundleFile:3.4.5] at com.ctc.wstx.io.UTF8Writer.flush(UTF8Writer.java:100) ~[?:?] at com.ctc.wstx.sw.BufferingXmlWriter.flush(BufferingXmlWriter.java:242) ~[?:?] at com.ctc.wstx.sw.BaseStreamWriter.flush(BaseStreamWriter.java:260) ~[?:?] at org.apache.cxf.jaxrs.interceptor.JAXRSDefaultFaultOutInterceptor.handleMessage(JAXRSDefaultFaultOutInterceptor.java:104) ~[bundleFile:3.4.5] ... 51 more
J-N-K
commented
2 years ago Thanks. It seems that the connections is successfully restored and then dropped again immediately. This could be due to an old instance of the channel hanging around somewhere. I added more logging to check that, can you update again?
The second exception seems to be unrelated.
BigGeorgeTx
commented
2 years ago OK. I removed the binding and added the new one. I will report back what I see in the logs when the devices cycles.
BigGeorgeTx
commented
2 years ago I think this captures it dropping and reconnecting:
2022-03-28 08:05:27.020 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending ping 2022-03-28 08:05:27.021 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending HEART_BEAT, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1648472727, dps=, gwId=ebb442e79ea9c02fb35xs8} 2022-03-28 08:05:27.022 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000002290000000900000097332E33000000000000000000000000989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1BDF5A5E87393164D97E452F3789D17A461F63C7DFED8F463F23B1DF6A79777BDDBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F0041F96340000AA55' 2022-03-28 08:05:27.056 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-28 08:05:27.058 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-28 08:05:27.165 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD8F08E1A21472AEB7C7EA6D979A919E00A6000871662789E023EEDBAC9EBFB51C24B7C6CB6C84FC4998BA77D267A7EEA7256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4397D9948220000AA55' 2022-03-28 08:05:27.167 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.208","gwId":"eb04ec86abf62e08b1932f","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-28 08:05:27.169 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.208', deviceId='eb04ec86abf62e08b1932f', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-28 08:05:27.218 [DEBUG] [rnal.local.handlers.UserEventHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: IOException caught, closing channel. java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcherImpl.read0(Native Method) ~[?:?] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) ~[?:?] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:276) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:233) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:223) ~[?:?] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:356) ~[?:?] at io.netty.buffer.PooledByteBuf.setBytes(PooledByteBuf.java:253) ~[bundleFile:4.1.72.Final] at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1132) ~[bundleFile:4.1.72.Final] at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:350) ~[bundleFile:4.1.72.Final] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:151) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) [bundleFile:4.1.72.Final] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.72.Final] at java.lang.Thread.run(Thread.java:829) [?:?] 2022-03-28 08:05:27.222 [DEBUG] [rnal.local.handlers.UserEventHandler] - IOException caught: java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcherImpl.read0(Native Method) ~[?:?] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) ~[?:?] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:276) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:233) ~[?:?] at sun.nio.ch.IOUtil.read(IOUtil.java:223) ~[?:?] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:356) ~[?:?] at io.netty.buffer.PooledByteBuf.setBytes(PooledByteBuf.java:253) ~[bundleFile:4.1.72.Final] at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:1132) ~[bundleFile:4.1.72.Final] at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:350) ~[bundleFile:4.1.72.Final] at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:151) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584) [bundleFile:4.1.72.Final] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) [bundleFile:4.1.72.Final] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.72.Final] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.72.Final] at java.lang.Thread.run(Thread.java:829) [?:?] 2022-03-28 08:05:27.226 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. [id: 0xd6f97f17, L:/192.168.1.89:38902 ! R:/192.168.1.226:6668] 2022-03-28 08:05:27.229 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 08:05:27.370 [TRACE] [rnal.local.handlers.HeartbeatHandler] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Sending ping 2022-03-28 08:05:27.371 [DEBUG] [.internal.local.handlers.TuyaEncoder] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Sending HEART_BEAT, payload {devId=eb04ec86abf62e08b1932f, uid=eb04ec86abf62e08b1932f, t=1648472727, dps=, gwId=eb04ec86abf62e08b1932f} 2022-03-28 08:05:27.373 [TRACE] [.internal.local.handlers.TuyaEncoder] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Sending encoded '000055AA000009A00000000900000097332E33000000000000000000000000884BA48AE77EEF9C8887C9E03EA286FA99CCB1BC6CC1571433D715FD6F0171E40CABE003D80FDEAC3942C10D88A76BE7E9BE2A8919E36DA74F6F4DCDA7E937117A7FA8CD6ADCDB84AFFB9CD7DD8705EED02A639B96E21F49346F5BCC4649AD4A877AABDF29FC73EC6D1C51E4D6329DF13E2DA997A2F211F4B4E4531FE87F7720B2CD14C20000AA55' 2022-03-28 08:05:27.411 [TRACE] [.internal.local.handlers.TuyaDecoder] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:27.412 [TRACE] [.internal.local.handlers.TuyaDecoder] - eb04ec86abf62e08b1932f//192.168.1.208:6668: Decoded raw payload: 2022-03-28 08:05:27.414 [DEBUG] [.internal.local.handlers.TuyaDecoder] - eb04ec86abf62e08b1932f//192.168.1.208:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:27.415 [TRACE] [rnal.local.handlers.HeartbeatHandler] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Received pong 2022-03-28 08:05:27.902 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AB5CC3CB4691A94082F96F1F413ADCDFD204C082BA5001B8F04F84D572A6D06012FD49AE1EB2FED8853E642A618B5ED077256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786F4C1F700BD0A36716389950D991E16BAA2647AE2696B993AD82600478BF988287A23E3B2249F5ADF48A714F851AAF439014E4A910000AA55' 2022-03-28 08:05:27.904 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.236","gwId":"eb349b6012eb05c13103yh","active":2,"ablilty":0,"encrypt":true,"productKey":"keydqydsydtu759x","version":"3.3"} 2022-03-28 08:05:27.905 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.236', deviceId='eb349b6012eb05c13103yh', active=2, ability=0, mode=0, encrypt=true, productKey='keydqydsydtu759x', version='3.3'}'} 2022-03-28 08:05:28.222 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AFC41B78182A5D63EE2972DC4C24AF26DEB231811511651D6030733901EC3C44AF3AFEDCA50D58DBC2B584825DCE135647256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4392877DE100000AA55' 2022-03-28 08:05:28.225 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.148","gwId":"ebf3cb07f3574a9688z4oy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-28 08:05:28.227 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.148', deviceId='ebf3cb07f3574a9688z4oy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-28 08:05:28.343 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-28 08:05:28.345 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-28 08:05:28.347 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-28 08:05:29.854 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 04200320b4e62d07ff0b/192.168.1.242:6668: Sending ping 2022-03-28 08:05:29.856 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 04200320b4e62d07ff0b/192.168.1.242:6668: Sending HEART_BEAT, payload {devId=04200320b4e62d07ff0b, uid=04200320b4e62d07ff0b, t=1648472729, dps=, gwId=04200320b4e62d07ff0b} 2022-03-28 08:05:29.858 [TRACE] [.internal.local.handlers.TuyaEncoder] - 04200320b4e62d07ff0b/192.168.1.242:6668: Sending encoded '000055AA000009A3000000090000007B7B226465764964223A223034323030333230623465363264303766663062222C22756964223A223034323030333230623465363264303766663062222C2274223A313634383437323732392C22647073223A22222C2267774964223A223034323030333230623465363264303766663062227D63827C910000AA55' 2022-03-28 08:05:29.866 [TRACE] [.internal.local.handlers.TuyaDecoder] - 04200320b4e62d07ff0b/192.168.1.242:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:29.869 [TRACE] [.internal.local.handlers.TuyaDecoder] - 04200320b4e62d07ff0b//192.168.1.242:6668: Decoded raw payload: 2022-03-28 08:05:29.871 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 04200320b4e62d07ff0b//192.168.1.242:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:29.872 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 04200320b4e62d07ff0b/192.168.1.242:6668: Received pong 2022-03-28 08:05:30.066 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-28 08:05:30.068 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-28 08:05:31.177 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 800061668caab5005de9/192.168.1.165:6668: Sending ping 2022-03-28 08:05:31.179 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 800061668caab5005de9/192.168.1.165:6668: Sending HEART_BEAT, payload {devId=800061668caab5005de9, uid=800061668caab5005de9, t=1648472731, dps=, gwId=800061668caab5005de9} 2022-03-28 08:05:31.181 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Sending ping 2022-03-28 08:05:31.181 [TRACE] [.internal.local.handlers.TuyaEncoder] - 800061668caab5005de9/192.168.1.165:6668: Sending encoded '000055AA0000120C0000000900000097332E330000000000000000000000003F1D90E8F4571FC9E0922F0231FCA06DE51402A5D51CB6E58C77E2F32751D05B358737B299305ECE9A199A078B29942E2592EE4F9E84EAFF1CBDE8048E10C82871D2BD6BCFFBF4C59BB5277FE97538D0CC5C06821E288C744F9ED0390B3A8C90DF62F45FC5D845B79655F054286BBCE64529869FBFF3E41B65A132DA6D472C20FF4AA7490000AA55' 2022-03-28 08:05:31.184 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending ping 2022-03-28 08:05:31.184 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Sending ping 2022-03-28 08:05:31.185 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending HEART_BEAT, payload {devId=36802558840d8e95ef5d, uid=36802558840d8e95ef5d, t=1648472731, dps=, gwId=36802558840d8e95ef5d} 2022-03-28 08:05:31.186 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Sending HEART_BEAT, payload {devId=ebf3cb07f3574a9688z4oy, uid=ebf3cb07f3574a9688z4oy, t=1648472731, dps=, gwId=ebf3cb07f3574a9688z4oy} 2022-03-28 08:05:31.186 [TRACE] [.internal.local.handlers.TuyaEncoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Sending encoded '000055AA000009A3000000090000007B7B226465764964223A223336383032353538383430643865393565663564222C22756964223A223336383032353538383430643865393565663564222C2274223A313634383437323733312C22647073223A22222C2267774964223A223336383032353538383430643865393565663564227DD00C08D60000AA55' 2022-03-28 08:05:31.187 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a3734e6/192.168.1.229:6668: Sending HEART_BEAT, payload {devId=42434288807d3a3734e6, uid=42434288807d3a3734e6, t=1648472731, dps=, gwId=42434288807d3a3734e6} 2022-03-28 08:05:31.188 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Sending encoded '000055AA000009A10000000900000097332E33000000000000000000000000AEBA5950965BC2EC9CD11FC6CF23BEB05FBCEEDB42C2FAA132619EAD89ADFB20B66FB78D4BC68658C93254134121DE5EF089BDD224EC53F1E310524C3D84C82CF2108ED9B57BFDBC8FB120FC98062E7E580F5B12AC34150CB2B81A2B280AA6FBDF1FC976AE8F9003D418274707BCF1E37DE334016CFAD800FC1D4F9292D87AA8FEF520290000AA55' 2022-03-28 08:05:31.189 [TRACE] [.internal.local.handlers.TuyaEncoder] - 42434288807d3a3734e6/192.168.1.229:6668: Sending encoded '000055AA000009A2000000090000007B7B226465764964223A223432343334323838383037643361333733346536222C22756964223A223432343334323838383037643361333733346536222C2274223A313634383437323733312C22647073223A22222C2267774964223A223432343334323838383037643361333733346536227D1E8A79230000AA55' 2022-03-28 08:05:31.195 [TRACE] [.internal.local.handlers.TuyaDecoder] - 800061668caab5005de9/192.168.1.165:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:31.196 [TRACE] [.internal.local.handlers.TuyaDecoder] - 800061668caab5005de9//192.168.1.165:6668: Decoded raw payload: 2022-03-28 08:05:31.198 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 800061668caab5005de9//192.168.1.165:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:31.199 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 800061668caab5005de9/192.168.1.165:6668: Received pong 2022-03-28 08:05:31.200 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6/192.168.1.229:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:31.200 [TRACE] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d/192.168.1.244:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:31.201 [TRACE] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6//192.168.1.229:6668: Decoded raw payload: 2022-03-28 08:05:31.201 [TRACE] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d//192.168.1.244:6668: Decoded raw payload: 2022-03-28 08:05:31.202 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 36802558840d8e95ef5d//192.168.1.244:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:31.202 [DEBUG] [.internal.local.handlers.TuyaDecoder] - 42434288807d3a3734e6//192.168.1.229:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:31.202 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Received pong 2022-03-28 08:05:31.203 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Received pong 2022-03-28 08:05:31.240 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD2E27C5F32F3E3F0AFC41C85D5F380E6FAAA67378A1AD8D82C8881CF61B1ED1C17CDF83527CCE8ED665CF186940C31867256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786D6580B90DE59FFECA847456AFC569A70F961BDD6290849510943F0B3DEDC720E7A23E3B2249F5ADF48A714F851AAF43999DF41830000AA55' 2022-03-28 08:05:31.241 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.206","gwId":"ebb8edc2de0ce2e480xkxw","active":2,"ablilty":0,"encrypt":true,"productKey":"opqqmfjz7y2o6y6a","version":"3.3"} 2022-03-28 08:05:31.242 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.206', deviceId='ebb8edc2de0ce2e480xkxw', active=2, ability=0, mode=0, encrypt=true, productKey='opqqmfjz7y2o6y6a', version='3.3'}'} 2022-03-28 08:05:31.300 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-28 08:05:31.301 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy//192.168.1.148:6668: Decoded raw payload: 2022-03-28 08:05:31.303 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy//192.168.1.148:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-28 08:05:31.304 [TRACE] [rnal.local.handlers.HeartbeatHandler] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Received pong 2022-03-28 08:05:31.706 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000CC00000000D09766676F3369EB10B5E9F132FD802AE33F0A22D663644012FC8D872BCF03D07B64C8DD4401899000716D5170D1858B0B1B576FA1E157538A210ED25F4C31067256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE57978641CBF9244804CF3DC911CADA0E75770803BF05AC9D8E6CCA4A3B9E5968A55B0DCC37291424EC2B5401F4F50D7EB6A63204AF131AE1CD3F0E4AB9A53DA72F98A8DE9F9AC76E33C955A8D1F62F82FAB781D482232E3BD3BF5CF00BC08816559FD69B99BF8B0000AA55' 2022-03-28 08:05:31.708 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.246","gwId":"eb755c84e755bbd761ymwj","active":2,"ablilty":0,"encrypt":true,"productKey":"keyyj3fy8x98arty","version":"3.3","lan_cap":5000,"lan_seq":26,"token":true} 2022-03-28 08:05:31.712 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.246', deviceId='eb755c84e755bbd761ymwj', active=2, ability=0, mode=0, encrypt=true, productKey='keyyj3fy8x98arty', version='3.3'}'} 2022-03-28 08:05:32.086 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A3EDB2FB5FEFF0568F68195638EDEDF92FC42CFBC87C56B05EADFF8A6A44376C01AC8F3F3C78B01106FB63E08EE964D247256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF439EB3C8AB40000AA55' 2022-03-28 08:05:32.088 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.180","gwId":"eb3c82adf880a32bbe5ivy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-28 08:05:32.089 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.180', deviceId='eb3c82adf880a32bbe5ivy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-28 08:05:32.231 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-28 08:05:32.319 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0xf161a360, L:/192.168.1.89:37750 - R:/192.168.1.226:6668] 2022-03-28 08:05:32.320 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending DP_QUERY, payload {devId=ebb442e79ea9c02fb35xs8, uid=ebb442e79ea9c02fb35xs8, t=1648472732, dps=null, gwId=ebb442e79ea9c02fb35xs8} 2022-03-28 08:05:32.322 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Sending encoded '000055AA000000010000000A00000088989E66990DA65E64E677F89C1A37CD781B4F53399466FC6F64017A29EEEB9E311C86B77199DEBC0A4BDB39C566CB46D8546E834D6551BAB447158BEA8B57BDB1E4EECA12BD31C3B104BF5A47C839D0947F1B2D609EAC5C7A1D79CEFAEC007DB1DBFEBD5110A3B297FDC1B16867D92B68FC7E7994C936921F65B66225AAA1E1F07DF6BE310000AA55' 2022-03-28 08:05:32.324 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0xf161a360, L:/192.168.1.89:37750 - R:/192.168.1.226:6668] 2022-03-28 08:05:32.377 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 21410372c44f33b896bf/192.168.1.227:6668: Sending ping 2022-03-28 08:05:32.378 [DEBUG] [.internal.local.handlers.TuyaEncoder] - 21410372c44f33b896bf/192.168.1.227:6668: Sending HEART_BEAT, payload {devId=21410372c44f33b896bf, uid=21410372c44f33b896bf, t=1648472732, dps=, gwId=21410372c44f33b896bf} 2022-03-28 08:05:32.380 [TRACE] [.internal.local.handlers.TuyaEncoder] - 21410372c44f33b896bf/192.168.1.227:6668: Sending encoded '000055AA0000120C0000000900000097332E330000000000000000000000006EEA753E1E903275D77483DA455AB45A98EE425267A06575E5C49CE6D9B3F5CB1005D68967C37D2351A4DFA03B6F9607D303FF7463E04A5AC97DE757BC5F62B52414CF1CE36DCB099DF81BC16F1D487575363F6449E12DE469A72743A413B00139D718CA131809CCD812CA7872EE04B5E230326D419C20CB00CC8AF5A8BC4418E8C35AA80000AA55' 2022-03-28 08:05:32.381 [TRACE] [rnal.local.handlers.HeartbeatHandler] - 601867532462ab3a32c4/192.168.1.228:6668: Sending ping 2022
J-N-K
commented
2 years ago That looks good. Immediately after
2022-03-28 08:05:32.324 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0xf161a360, L:/192.168.1.89:37750 - R:/192.168.1.226:6668]the thing should switch to ONLINE. Is that the case? There is not much that can go wrong:
@Override
public void channelActive(@NonNullByDefault({}) ChannelHandlerContext ctx) throws Exception {
// TODO: revert to debug
logger.error("{}{}: Connection established. {}", deviceId,
Objects.requireNonNullElse(ctx.channel().remoteAddress(), ""), ctx.channel());
deviceStatusListener.connectionStatus(true);
}and
@Override
public void connectionStatus(boolean status) {
if (status) {
updateStatus(ThingStatus.ONLINE);
} else {
updateStatus(ThingStatus.OFFLINE);
TuyaDevice tuyaDevice = this.tuyaDevice;
// TODO: revert to TRACE
logger.error("{}: offline, scheduling reconnect in 5 s, state is tuyaDevice={} / disposing={}",
thing.getUID(), tuyaDevice, disposing);
if (tuyaDevice != null && !disposing) {
reconnectFuture = scheduler.schedule(tuyaDevice::connect, 5000, TimeUnit.MILLISECONDS);
}
}
}From the log a little bit above it looks like this happens:
2022-03-21 15:08:32.736 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE
2022-03-21 15:08:33.147 [TRACE] [a.internal.handler.TuyaDeviceHandler] - 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' received status message '{20=false, 21=white, 22=250.0, 23=220.0, 24=00b403e803e8, 25=000e0d0000000000000000c80000, 26=0.0}'
2022-03-21 15:08:53.825 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE
2022-0but then the thing goes back OFFLINE. Can you check if this happened and what if there are log messages related to ebb442e79ea9c02fb35xs8 which tell us a reason for that?
BigGeorgeTx
commented
2 years ago I don't see it going offline, just disconnecting and reconnecting. Here is an INFO level log:
2022-03-28 11:13:29.657 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. [id: 0xf0610b30, L:/192.168.1.89:36006 ! R:/192.168.1.226:6668] 2022-03-28 11:13:29.660 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 11:13:34.662 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-28 11:13:34.690 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0xc436fae9, L:/192.168.1.89:36160 - R:/192.168.1.226:6668] 2022-03-28 11:16:46.880 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. [id: 0xc436fae9, L:/192.168.1.89:36160 ! R:/192.168.1.226:6668] 2022-03-28 11:16:46.884 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 11:16:51.887 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-28 11:16:52.005 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0x8d582e86, L:/192.168.1.89:37082 - R:/192.168.1.226:6668] 2022-03-28 11:17:12.180 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. [id: 0x8d582e86, L:/192.168.1.89:37082 ! R:/192.168.1.226:6668] 2022-03-28 11:17:12.182 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 11:17:17.184 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-28 11:17:17.297 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0x9664cce4, L:/192.168.1.89:37212 - R:/192.168.1.226:6668]
and
2022-03-28 11:46:57.839 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 11:47:02.958 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE 2022-03-28 11:47:23.943 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 11:47:23.945 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE 2022-03-28 11:47:29.068 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE
So it drops offline and comes quickly back on line. My question what happens if I send a command during that brief period. Will it remember and execute the command after it comes back online? (like an MQTT QOS 1).
I need to conduct a test and reboot the router and see if they come back online.
BigGeorgeTx
commented
2 years ago Some additional devices cycling Offline then back Online. They all came back very quickly.
2022-03-28 17:20:21.137 [ERROR] [al.local.handlers.TuyaMessageHandler] - 04200320b4e62d07ff0b/192.168.1.242:6668: Connection terminated. [id: 0x3f208c45, L:/192.168.1.89:43024 ! R:/192.168.1.242:6668] 2022-03-28 17:20:21.139 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:04200320b4e62d07ff0b: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='04200320b4e62d07ff0b', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@143f332, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:21.140 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:04200320b4e62d07ff0b' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:21.711 [ERROR] [al.local.handlers.TuyaMessageHandler] - 42434288807d3a372fd5/192.168.1.245:6668: Connection terminated. [id: 0xd3f0cdda, L:/192.168.1.89:41754 ! R:/192.168.1.245:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:21.715 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a372fd5' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:21.713 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:42434288807d3a372fd5: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='42434288807d3a372fd5', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@48aafc, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false 2022-03-28 17:20:21.773 [ERROR] [al.local.handlers.TuyaMessageHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Connection terminated. [id: 0xe774c864, L:/192.168.1.89:44126 ! R:/192.168.1.229:6668] 2022-03-28 17:20:21.775 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:42434288807d3a3734e6: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='42434288807d3a3734e6', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@50cfdb, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:21.777 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:22.642 [ERROR] [al.local.handlers.TuyaMessageHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Connection terminated. [id: 0xb98c41cd, L:/192.168.1.89:36828 ! R:/192.168.1.244:6668] 2022-03-28 17:20:22.645 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:36802558840d8e95ef5d: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='36802558840d8e95ef5d', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@13f0df2, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:22.646 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:36802558840d8e95ef5d' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:26.142 [ERROR] [nding.tuya.internal.local.TuyaDevice] - 04200320b4e62d07ff0b is trying to connect 2022-03-28 17:20:26.156 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x01adc30f, L:/192.168.1.89:42852 - R:/192.168.1.242:6668] 2022-03-28 17:20:26.159 [ERROR] [al.local.handlers.TuyaMessageHandler] - 04200320b4e62d07ff0b/192.168.1.242:6668: Connection established. [id: 0x01adc30f, L:/192.168.1.89:42852 - R:/192.168.1.242:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:26.162 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:04200320b4e62d07ff0b' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:26.169 [WARN ] [inding.tuya.internal.util.CryptoUtil] - Decryption of MQ failed: Input length not multiple of 16 bytes 2022-03-28 17:20:26.717 [ERROR] [nding.tuya.internal.local.TuyaDevice] - 42434288807d3a372fd5 is trying to connect 2022-03-28 17:20:26.777 [ERROR] [nding.tuya.internal.local.TuyaDevice] - 42434288807d3a3734e6 is trying to connect 2022-03-28 17:20:26.865 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0xf8ac8794, L:/192.168.1.89:43938 - R:/192.168.1.229:6668] 2022-03-28 17:20:26.868 [ERROR] [al.local.handlers.TuyaMessageHandler] - 42434288807d3a3734e6/192.168.1.229:6668: Connection established. [id: 0xf8ac8794, L:/192.168.1.89:43938 - R:/192.168.1.229:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:26.870 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:26.874 [WARN ] [inding.tuya.internal.util.CryptoUtil] - Decryption of MQ failed: Input length not multiple of 16 bytes 2022-03-28 17:20:26.943 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x5ebd93c7, L:/192.168.1.89:41544 - R:/192.168.1.245:6668] 2022-03-28 17:20:26.946 [ERROR] [al.local.handlers.TuyaMessageHandler] - 42434288807d3a372fd5/192.168.1.245:6668: Connection established. [id: 0x5ebd93c7, L:/192.168.1.89:41544 - R:/192.168.1.245:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:26.949 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a372fd5' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:26.951 [WARN ] [inding.tuya.internal.util.CryptoUtil] - Decryption of MQ failed: Input length not multiple of 16 bytes 2022-03-28 17:20:27.648 [ERROR] [nding.tuya.internal.local.TuyaDevice] - 36802558840d8e95ef5d is trying to connect 2022-03-28 17:20:27.851 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0xc8fe9911, L:/192.168.1.89:36636 - R:/192.168.1.244:6668] 2022-03-28 17:20:27.854 [ERROR] [al.local.handlers.TuyaMessageHandler] - 36802558840d8e95ef5d/192.168.1.244:6668: Connection established. [id: 0xc8fe9911, L:/192.168.1.89:36636 - R:/192.168.1.244:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:27.858 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:36802558840d8e95ef5d' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:27.862 [WARN ] [inding.tuya.internal.util.CryptoUtil] - Decryption of MQ failed: Input length not multiple of 16 bytes ==> /var/log/openhab/events.log <== 2022-03-28 17:20:29.198 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'LocalTime_Date' changed from 2022-03-28T17:19:29.182-0500 to 2022-03-28T17:20:29.184-0500 ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:30.628 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection terminated. [id: 0x9c73499d, L:/192.168.1.89:57462 ! R:/192.168.1.226:6668] 2022-03-28 17:20:30.631 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:TuyaFrontEntrySmartLight: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebb442e79ea9c02fb35xs8', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f7a7c9, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:30.632 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:32.877 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb3c82adf880a32bbe5ivy/192.168.1.180:6668: Connection terminated. [id: 0x0803a5e7, L:/192.168.1.89:47358 ! R:/192.168.1.180:6668] 2022-03-28 17:20:32.880 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:eb3c82adf880a32bbe5ivy: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='eb3c82adf880a32bbe5ivy', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1edef84, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:32.881 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb3c82adf880a32bbe5ivy' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:35.635 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebb442e79ea9c02fb35xs8 is trying to connect 2022-03-28 17:20:35.731 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x1ad17905, L:/192.168.1.89:58840 - R:/192.168.1.226:6668] 2022-03-28 17:20:35.734 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebb442e79ea9c02fb35xs8/192.168.1.226:6668: Connection established. [id: 0x1ad17905, L:/192.168.1.89:58840 - R:/192.168.1.226:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:35.738 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:TuyaFrontEntrySmartLight' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:36.301 [WARN ] [rnal.local.handlers.HeartbeatHandler] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Missed more than 3 heartbeat responses. Connection seems to be dead. 2022-03-28 17:20:36.303 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Connection terminated. [id: 0x36288401, L:/192.168.1.89:52988 ! R:/192.168.1.208:6668] 2022-03-28 17:20:36.305 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:eb04ec86abf62e08b1932f: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='eb04ec86abf62e08b1932f', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1c14523, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:36.306 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb04ec86abf62e08b1932f' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:37.354 [WARN ] [rnal.local.handlers.HeartbeatHandler] - eb349b6012eb05c13103yh/192.168.1.236:6668: Missed more than 3 heartbeat responses. Connection seems to be dead. 2022-03-28 17:20:37.357 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb349b6012eb05c13103yh/192.168.1.236:6668: Connection terminated. [id: 0x54780ac6, L:/192.168.1.89:34222 ! R:/192.168.1.236:6668] 2022-03-28 17:20:37.360 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:eb349b6012eb05c13103yh: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='eb349b6012eb05c13103yh', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@126bfac, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:37.362 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb349b6012eb05c13103yh' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:37.881 [ERROR] [nding.tuya.internal.local.TuyaDevice] - eb3c82adf880a32bbe5ivy is trying to connect 2022-03-28 17:20:38.088 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x610afc02, L:/192.168.1.89:47146 - R:/192.168.1.180:6668] 2022-03-28 17:20:38.090 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb3c82adf880a32bbe5ivy/192.168.1.180:6668: Connection established. [id: 0x610afc02, L:/192.168.1.89:47146 - R:/192.168.1.180:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:38.093 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb3c82adf880a32bbe5ivy' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:39.131 [WARN ] [rnal.local.handlers.HeartbeatHandler] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Missed more than 3 heartbeat responses. Connection seems to be dead. 2022-03-28 17:20:39.134 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Connection terminated. [id: 0x27c7fb8a, L:/192.168.1.89:57548 ! R:/192.168.1.148:6668] 2022-03-28 17:20:39.137 [ERROR] [a.internal.handler.TuyaDeviceHandler] - tuya:tuyaDevice:ebf3cb07f3574a9688z4oy: offline, scheduling reconnect in 5 s, state is tuyaDevice=deviceId='ebf3cb07f3574a9688z4oy', channel={active=false,open=false}, bootstrap=BootstrapConfig(group: NioEventLoopGroup, channelFactory: ReflectiveChannelFactory(NioSocketChannel.class), options: {TCP_NODELAY=true, CONNECT_TIMEOUT_MILLIS=2000}, handler: org.smarthomej.binding.tuya.internal.local.TuyaDevice$1@1f27f41, resolver: io.netty.resolver.DefaultAddressResolverGroup@12d44c5) / disposing=false ==> /var/log/openhab/events.log <== 2022-03-28 17:20:39.139 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from ONLINE to OFFLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:41.307 [ERROR] [nding.tuya.internal.local.TuyaDevice] - eb04ec86abf62e08b1932f is trying to connect 2022-03-28 17:20:41.376 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x9218a447, L:/192.168.1.89:52772 - R:/192.168.1.208:6668] 2022-03-28 17:20:41.378 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb04ec86abf62e08b1932f/192.168.1.208:6668: Connection established. [id: 0x9218a447, L:/192.168.1.89:52772 - R:/192.168.1.208:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:41.382 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb04ec86abf62e08b1932f' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:42.362 [ERROR] [nding.tuya.internal.local.TuyaDevice] - eb349b6012eb05c13103yh is trying to connect 2022-03-28 17:20:42.490 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x3b068b25, L:/192.168.1.89:34052 - R:/192.168.1.236:6668] 2022-03-28 17:20:42.493 [ERROR] [al.local.handlers.TuyaMessageHandler] - eb349b6012eb05c13103yh/192.168.1.236:6668: Connection established. [id: 0x3b068b25, L:/192.168.1.89:34052 - R:/192.168.1.236:6668] ==> /var/log/openhab/events.log <== 2022-03-28 17:20:42.497 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb349b6012eb05c13103yh' changed from OFFLINE to ONLINE ==> /var/log/openhab/openhab.log <== 2022-03-28 17:20:44.138 [ERROR] [nding.tuya.internal.local.TuyaDevice] - ebf3cb07f3574a9688z4oy is trying to connect 2022-03-28 17:20:44.232 [ERROR] [nding.tuya.internal.local.TuyaDevice] - Connect Channel [id: 0x91953e9c, L:/192.168.1.89:57364 - R:/192.168.1.148:6668] 2022-03-28 17:20:44.234 [ERROR] [al.local.handlers.TuyaMessageHandler] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Connection established. [id: 0x91953e9c, L:/192.168.1.89:57364 - R:/192.168.1.148:6668]
J-N-K
commented
2 years ago My original understanding was that the devices do not come back ONLINE. If they come back online, it works as designed.
BigGeorgeTx
commented
2 years ago Initially they did not. Somewhere along the way with the changes you have made, they started coming back. I'll look back and try to identify when that was. I also still need to reboot the router and see what happens.
On Tue, Mar 29, 2022 at 5:37 AM J-N-K @.***> wrote:
My original understanding was that the devices do not come back ONLINE. If they come back online, it works as designed.
— Reply to this email directly, view it on GitHub https://github.com/smarthomej/addons/issues/341#issuecomment-1081709184, or unsubscribe https://github.com/notifications/unsubscribe-auth/ADEQZL5OPX5UZWHKUZZRIMLVCLMNDANCNFSM5QKBVVDA . You are receiving this because you authored the thread.Message ID: @.***>
-- George Hyde Mobile +1-281-620-3039
J-N-K
commented
2 years ago Unless the issue (devices stay offline after router reboot) comes back, I'll close here.
I have observed that when I have to reboot my router for whatever reason, afterwards, all of the Tuya devices are offline. I can fix that by 1) restarting openHAB, 2) going to each Tuya Thing and disabling and the reenabling it, or 3) stopping and starting the bundle (bundle:stop nnn, bundle:start nnn from the Karaf console.
Here is a log output. I didn't have TRACE set when I rebooted the router, but turned it on later. If it would be helpful to have a TRACE of the full reboot cycle, please let me know and I will provide it.
2022-03-09 09:53:52.899 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb3c82adf880a32bbe5ivy' changed from ONLINE to OFFLINE 2022-03-09 09:53:54.806 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:04200320b4e62d07ff0b' changed from ONLINE to OFFLINE 2022-03-09 09:53:54.808 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a372fd5' changed from ONLINE to OFFLINE 2022-03-09 09:53:58.239 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:601867532462ab3a32c4' changed from ONLINE to OFFLINE 2022-03-09 09:53:59.034 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:36802558840d8e95ef5d' changed from ONLINE to OFFLINE 2022-03-09 09:53:59.036 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:21410372c44f33b896bf' changed from ONLINE to OFFLINE 2022-03-09 09:53:59.039 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from ONLINE to OFFLINE 2022-03-09 09:53:59.041 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb349b6012eb05c13103yh' changed from ONLINE to OFFLINE 2022-03-09 09:53:59.325 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:eb04ec86abf62e08b1932f' changed from ONLINE to OFFLINE 2022-03-09 09:54:01.933 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:42434288807d3a3734e6' changed from ONLINE to OFFLINE 2022-03-09 09:55:16.215 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 09:55:21.923 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 09:55:16.215 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:00:16.217 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:00:21.933 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 10:00:16.217 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:05:16.218 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:05:21.942 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 10:05:16.218 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:09:50.818 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from OFFLINE to UNINITIALIZED 2022-03-09 10:09:50.834 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from UNINITIALIZED to UNINITIALIZED (DISABLED) 2022-03-09 10:09:52.519 [INFO ] [a.internal.handler.TuyaDeviceHandler] - Configuring IP address 'DeviceInfo{ip='192.168.1.148', version='3.3'}' for thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy'. 2022-03-09 10:09:52.511 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from UNINITIALIZED (DISABLED) to INITIALIZING 2022-03-09 10:09:52.520 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from INITIALIZING to OFFLINE (CONFIGURATION_PENDING): Waiting for IP address 2022-03-09 10:09:52.524 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from OFFLINE (CONFIGURATION_PENDING): Waiting for IP address to UNKNOWN 2022-03-09 10:09:52.646 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'tuya:tuyaDevice:ebf3cb07f3574a9688z4oy' changed from UNKNOWN to ONLINE 2022-03-09 10:10:16.221 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:10:21.959 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 10:10:16.221 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:15:16.223 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:15:21.968 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 10:15:16.223 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:20:16.225 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:20:21.992 [INFO ] [openhab.event.ChannelTriggeredEvent ] - logreader:reader:LogReaderOpenHABCloudConnectStatus:newWarningEvent triggered 2022-03-09 10:20:16.225 [WARN ] [g.tuya.internal.TuyaDiscoveryService] - Tried to start scan but API for bridge 'tuya:project:dc58ac9d8e' is not connected. 2022-03-09 10:22:52.949 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000CC00000000D09766676F3369EB10B5E9F132FD802AE33F0A22D663644012FC8D872BCF03D07B64C8DD4401899000716D5170D1858B0B1B576FA1E157538A210ED25F4C31067256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE57978641CBF9244804CF3DC911CADA0E75770803BF05AC9D8E6CCA4A3B9E5968A55B0DCC37291424EC2B5401F4F50D7EB6A63204AF131AE1CD3F0E4AB9A53DA72F98A8617CB007FF58786669D79C94FB8EA213D482232E3BD3BF5CF00BC08816559FD6A790AEB30000AA55' 2022-03-09 10:22:52.950 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.246","gwId":"eb755c84e755bbd761ymwj","active":2,"ablilty":0,"encrypt":true,"productKey":"keyyj3fy8x98arty","version":"3.3","lan_cap":5000,"lan_seq":25,"token":true} 2022-03-09 10:22:52.951 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.246', deviceId='eb755c84e755bbd761ymwj', active=2, ability=0, mode=0, encrypt=true, productKey='keyyj3fy8x98arty', version='3.3'}'} 2022-03-09 10:22:53.118 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323239222C2267774964223A223432343334323838383037643361333733346536222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227D9F9F52270000AA55' 2022-03-09 10:22:53.122 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.229', deviceId='42434288807d3a3734e6', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:22:54.882 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000AC00000000D09766676F3369EB10B5E9F132FD802AF54E6EB0CDDDD51B349B4D3F37F437C4D9E3CEF77BA842032692CD099E7D5060D88BFB88C3BDF3E986B3DFC5676536EC4CB2E96C0A7F1E413BAED18D2F9CE89DD98A2083CC863E53249CDFED5EC93122B4B2BBEECE5882C45B23697E1DAAC6FCFF428041EC6AB57E8BABF113E3B2A682B42FB4D382DA37498C8DDD04590FD752768FFC6AB334859566B8DB83B5BC2215E4748C680000AA55' 2022-03-09 10:22:54.883 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.227","gwId":"21410372c44f33b896bf","active":2,"ability":0,"mode":0,"encrypt":true,"productKey":"keyj979nf3q3theh","version":"3.3"} 2022-03-09 10:22:54.884 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.227', deviceId='21410372c44f33b896bf', active=2, ability=0, mode=0, encrypt=true, productKey='keyj979nf3q3theh', version='3.3'}'} 2022-03-09 10:22:55.126 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323432222C2267774964223A223034323030333230623465363264303766663062222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A226F6630564475466357734254667A5774222C2276657273696F6E223A22332E31227D158B86960000AA55' 2022-03-09 10:22:55.129 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.242', deviceId='04200320b4e62d07ff0b', active=2, ability=0, mode=0, encrypt=true, productKey='of0VDuFcWsBTfzWt', version='3.1'}'} 2022-03-09 10:22:55.133 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323435222C2267774964223A223432343334323838383037643361333732666435222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227DE94393400000AA55' 2022-03-09 10:22:55.135 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.245', deviceId='42434288807d3a372fd5', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:22:55.145 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-09 10:22:55.147 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-09 10:22:55.148 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-09 10:22:55.345 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AB5CC3CB4691A94082F96F1F413ADCDFD204C082BA5001B8F04F84D572A6D06012FD49AE1EB2FED8853E642A618B5ED077256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786F4C1F700BD0A36716389950D991E16BAA2647AE2696B993AD82600478BF988287A23E3B2249F5ADF48A714F851AAF439014E4A910000AA55' 2022-03-09 10:22:55.347 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.236","gwId":"eb349b6012eb05c13103yh","active":2,"ablilty":0,"encrypt":true,"productKey":"keydqydsydtu759x","version":"3.3"} 2022-03-09 10:22:55.348 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.236', deviceId='eb349b6012eb05c13103yh', active=2, ability=0, mode=0, encrypt=true, productKey='keydqydsydtu759x', version='3.3'}'} 2022-03-09 10:22:55.440 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AFC41B78182A5D63EE2972DC4C24AF26DEB231811511651D6030733901EC3C44AF3AFEDCA50D58DBC2B584825DCE135647256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4392877DE100000AA55' 2022-03-09 10:22:55.441 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.148","gwId":"ebf3cb07f3574a9688z4oy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:22:55.443 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.148', deviceId='ebf3cb07f3574a9688z4oy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:22:55.453 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A3EDB2FB5FEFF0568F68195638EDEDF92FC42CFBC87C56B05EADFF8A6A44376C01AC8F3F3C78B01106FB63E08EE964D247256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF439EB3C8AB40000AA55' 2022-03-09 10:22:55.455 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.180","gwId":"eb3c82adf880a32bbe5ivy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:22:55.457 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.180', deviceId='eb3c82adf880a32bbe5ivy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:22:55.499 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323434222C2267774964223A223336383032353538383430643865393565663564222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D61F813F90000AA55' 2022-03-09 10:22:55.501 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.244', deviceId='36802558840d8e95ef5d', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:22:55.681 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-09 10:22:55.683 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:22:55.956 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD8F08E1A21472AEB7C7EA6D979A919E00A6000871662789E023EEDBAC9EBFB51C24B7C6CB6C84FC4998BA77D267A7EEA7256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4397D9948220000AA55' 2022-03-09 10:22:55.958 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.208","gwId":"eb04ec86abf62e08b1932f","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:22:55.959 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.208', deviceId='eb04ec86abf62e08b1932f', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:22:56.118 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323239222C2267774964223A223432343334323838383037643361333733346536222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227D9F9F52270000AA55' 2022-03-09 10:22:56.120 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.229', deviceId='42434288807d3a3734e6', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:22:57.253 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000AC00000000D09766676F3369EB10B5E9F132FD802AB81807EEB874F86515A2AA1F039E69EFBB2A9B17E46900F99197DD9F785469AA72ACB593045F1560BF69176A8D6562264CB2E96C0A7F1E413BAED18D2F9CE89DD98A2083CC863E53249CDFED5EC93122B4B2BBEECE5882C45B23697E1DAAC6FCFF428041EC6AB57E8BABF113E3B2A682B42FB4D382DA37498C8DDD04590FD752768FFC6AB334859566B8DB83B5BC2215A30F9A580000AA55' 2022-03-09 10:22:57.255 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.228","gwId":"601867532462ab3a32c4","active":2,"ability":0,"mode":0,"encrypt":true,"productKey":"keyj979nf3q3theh","version":"3.3"} 2022-03-09 10:22:57.256 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.228', deviceId='601867532462ab3a32c4', active=2, ability=0, mode=0, encrypt=true, productKey='keyj979nf3q3theh', version='3.3'}'} 2022-03-09 10:22:57.949 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000CC00000000D09766676F3369EB10B5E9F132FD802AE33F0A22D663644012FC8D872BCF03D07B64C8DD4401899000716D5170D1858B0B1B576FA1E157538A210ED25F4C31067256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE57978641CBF9244804CF3DC911CADA0E75770803BF05AC9D8E6CCA4A3B9E5968A55B0DCC37291424EC2B5401F4F50D7EB6A63204AF131AE1CD3F0E4AB9A53DA72F98A8617CB007FF58786669D79C94FB8EA213D482232E3BD3BF5CF00BC08816559FD6A790AEB30000AA55' 2022-03-09 10:22:57.952 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.246","gwId":"eb755c84e755bbd761ymwj","active":2,"ablilty":0,"encrypt":true,"productKey":"keyyj3fy8x98arty","version":"3.3","lan_cap":5000,"lan_seq":25,"token":true} 2022-03-09 10:22:57.953 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.246', deviceId='eb755c84e755bbd761ymwj', active=2, ability=0, mode=0, encrypt=true, productKey='keyyj3fy8x98arty', version='3.3'}'} 2022-03-09 10:22:58.126 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323432222C2267774964223A223034323030333230623465363264303766663062222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A226F6630564475466357734254667A5774222C2276657273696F6E223A22332E31227D158B86960000AA55' 2022-03-09 10:22:58.128 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.242', deviceId='04200320b4e62d07ff0b', active=2, ability=0, mode=0, encrypt=true, productKey='of0VDuFcWsBTfzWt', version='3.1'}'} 2022-03-09 10:22:58.132 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323435222C2267774964223A223432343334323838383037643361333732666435222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227DE94393400000AA55' 2022-03-09 10:22:58.134 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.245', deviceId='42434288807d3a372fd5', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:22:58.499 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323434222C2267774964223A223336383032353538383430643865393565663564222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D61F813F90000AA55' 2022-03-09 10:22:58.501 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.244', deviceId='36802558840d8e95ef5d', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:22:58.682 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-09 10:22:58.684 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:22:59.118 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323239222C2267774964223A223432343334323838383037643361333733346536222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227D9F9F52270000AA55' 2022-03-09 10:22:59.120 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.229', deviceId='42434288807d3a3734e6', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:22:59.899 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000AC00000000D09766676F3369EB10B5E9F132FD802AF54E6EB0CDDDD51B349B4D3F37F437C4D9E3CEF77BA842032692CD099E7D5060D88BFB88C3BDF3E986B3DFC5676536EC4CB2E96C0A7F1E413BAED18D2F9CE89DD98A2083CC863E53249CDFED5EC93122B4B2BBEECE5882C45B23697E1DAAC6FCFF428041EC6AB57E8BABF113E3B2A682B42FB4D382DA37498C8DDD04590FD752768FFC6AB334859566B8DB83B5BC2215E4748C680000AA55' 2022-03-09 10:22:59.901 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.227","gwId":"21410372c44f33b896bf","active":2,"ability":0,"mode":0,"encrypt":true,"productKey":"keyj979nf3q3theh","version":"3.3"} 2022-03-09 10:22:59.903 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.227', deviceId='21410372c44f33b896bf', active=2, ability=0, mode=0, encrypt=true, productKey='keyj979nf3q3theh', version='3.3'}'} 2022-03-09 10:23:00.149 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A54D88E00A3B4A4A2204F34DE830A29ED2E7325F499B9E9A8648DCA37AF2A0B57E739C6263C39E3377EBF023BC95EDF927256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786C53E2EC89795E9175FE31010E9BBE1E894A8C1D46E5D4AFF82F9EE5AB2265C147A23E3B2249F5ADF48A714F851AAF43904714B620000AA55' 2022-03-09 10:23:00.151 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.226","gwId":"ebb442e79ea9c02fb35xs8","active":2,"ablilty":0,"encrypt":true,"productKey":"keytg5kq8gvkv9dh","version":"3.3"} 2022-03-09 10:23:00.153 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.226', deviceId='ebb442e79ea9c02fb35xs8', active=2, ability=0, mode=0, encrypt=true, productKey='keytg5kq8gvkv9dh', version='3.3'}'} 2022-03-09 10:23:00.346 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AB5CC3CB4691A94082F96F1F413ADCDFD204C082BA5001B8F04F84D572A6D06012FD49AE1EB2FED8853E642A618B5ED077256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE579786F4C1F700BD0A36716389950D991E16BAA2647AE2696B993AD82600478BF988287A23E3B2249F5ADF48A714F851AAF439014E4A910000AA55' 2022-03-09 10:23:00.347 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.236","gwId":"eb349b6012eb05c13103yh","active":2,"ablilty":0,"encrypt":true,"productKey":"keydqydsydtu759x","version":"3.3"} 2022-03-09 10:23:00.347 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.236', deviceId='eb349b6012eb05c13103yh', active=2, ability=0, mode=0, encrypt=true, productKey='keydqydsydtu759x', version='3.3'}'} 2022-03-09 10:23:00.928 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AFC41B78182A5D63EE2972DC4C24AF26DEB231811511651D6030733901EC3C44AF3AFEDCA50D58DBC2B584825DCE135647256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4392877DE100000AA55' 2022-03-09 10:23:00.930 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.148","gwId":"ebf3cb07f3574a9688z4oy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:23:00.931 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.148', deviceId='ebf3cb07f3574a9688z4oy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:23:01.126 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323432222C2267774964223A223034323030333230623465363264303766663062222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A226F6630564475466357734254667A5774222C2276657273696F6E223A22332E31227D158B86960000AA55' 2022-03-09 10:23:01.128 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.242', deviceId='04200320b4e62d07ff0b', active=2, ability=0, mode=0, encrypt=true, productKey='of0VDuFcWsBTfzWt', version='3.1'}'} 2022-03-09 10:23:01.132 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323435222C2267774964223A223432343334323838383037643361333732666435222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227DE94393400000AA55' 2022-03-09 10:23:01.134 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.245', deviceId='42434288807d3a372fd5', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:23:01.500 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323434222C2267774964223A223336383032353538383430643865393565663564222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D61F813F90000AA55' 2022-03-09 10:23:01.501 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.244', deviceId='36802558840d8e95ef5d', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:23:01.509 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802A3EDB2FB5FEFF0568F68195638EDEDF92FC42CFBC87C56B05EADFF8A6A44376C01AC8F3F3C78B01106FB63E08EE964D247256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF439EB3C8AB40000AA55' 2022-03-09 10:23:01.511 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.180","gwId":"eb3c82adf880a32bbe5ivy","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:23:01.512 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.180', deviceId='eb3c82adf880a32bbe5ivy', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:23:01.683 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323437222C2267774964223A223336383032353538383430643865393566336334222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22704A6E70543058634D354654526A4F64222C2276657273696F6E223A22332E31227D157010A20000AA55' 2022-03-09 10:23:01.685 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.247', deviceId='36802558840d8e95f3c4', active=2, ability=0, mode=0, encrypt=true, productKey='pJnpT0XcM5FTRjOd', version='3.1'}'} 2022-03-09 10:23:01.897 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000130000009C00000000D09766676F3369EB10B5E9F132FD802AD8F08E1A21472AEB7C7EA6D979A919E00A6000871662789E023EEDBAC9EBFB51C24B7C6CB6C84FC4998BA77D267A7EEA7256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE5797868092865457F0696695F897C5D1D5127EF3FA80099F694CCB1412FB4BF23282687A23E3B2249F5ADF48A714F851AAF4397D9948220000AA55' 2022-03-09 10:23:01.899 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.208","gwId":"eb04ec86abf62e08b1932f","active":2,"ablilty":0,"encrypt":true,"productKey":"keygg897krt5kk4t","version":"3.3"} 2022-03-09 10:23:01.900 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.208', deviceId='eb04ec86abf62e08b1932f', active=2, ability=0, mode=0, encrypt=true, productKey='keygg897krt5kk4t', version='3.3'}'} 2022-03-09 10:23:02.118 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA00000000000000000000009F000000007B226970223A223139322E3136382E312E323239222C2267774964223A223432343334323838383037643361333733346536222C22616374697665223A322C226162696C697479223A302C226D6F6465223A302C22656E6372797074223A747275652C2270726F647563744B6579223A22496141366F465866447533746D315063222C2276657273696F6E223A22332E31227D9F9F52270000AA55' 2022-03-09 10:23:02.119 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP, content='DiscoveryMessage{ip='192.168.1.229', deviceId='42434288807d3a3734e6', active=2, ability=0, mode=0, encrypt=true, productKey='IaA6oFXfDu3tm1Pc', version='3.1'}'} 2022-03-09 10:23:02.253 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000AC00000000D09766676F3369EB10B5E9F132FD802AB81807EEB874F86515A2AA1F039E69EFBB2A9B17E46900F99197DD9F785469AA72ACB593045F1560BF69176A8D6562264CB2E96C0A7F1E413BAED18D2F9CE89DD98A2083CC863E53249CDFED5EC93122B4B2BBEECE5882C45B23697E1DAAC6FCFF428041EC6AB57E8BABF113E3B2A682B42FB4D382DA37498C8DDD04590FD752768FFC6AB334859566B8DB83B5BC2215A30F9A580000AA55' 2022-03-09 10:23:02.255 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener/: Decoded raw payload: {"ip":"192.168.1.228","gwId":"601867532462ab3a32c4","active":2,"ability":0,"mode":0,"encrypt":true,"productKey":"keyj979nf3q3theh","version":"3.3"} 2022-03-09 10:23:02.256 [DEBUG] [.internal.local.handlers.TuyaDecoder] - udpListener/: Received MessageWrapper{commandType=UDP_NEW, content='DiscoveryMessage{ip='192.168.1.228', deviceId='601867532462ab3a32c4', active=2, ability=0, mode=0, encrypt=true, productKey='keyj979nf3q3theh', version='3.3'}'} 2022-03-09 10:23:02.769 [DEBUG] [.internal.local.handlers.TuyaEncoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Sending HEART_BEAT, payload {devId=ebf3cb07f3574a9688z4oy, uid=ebf3cb07f3574a9688z4oy, t=1646842982, dps=, gwId=ebf3cb07f3574a9688z4oy} 2022-03-09 10:23:02.771 [TRACE] [.internal.local.handlers.TuyaEncoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Sending encoded '000055AA000000500000000900000097332E33000000000000000000000000AEBA5950965BC2EC9CD11FC6CF23BEB05FBCEEDB42C2FAA132619EAD89ADFB20B66FB78D4BC68658C93254134121DE5EF089BDD224EC53F1E310524C3D84C82C1A430F5DB433526F8B43BA3DF67D310F580F5B12AC34150CB2B81A2B280AA6FBDF1FC976AE8F9003D418274707BCF1E37DE334016CFAD800FC1D4F9292D87AA8A66D15800000AA55' 2022-03-09 10:23:02.863 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy/192.168.1.148:6668: Received encoded '000055AA00000000000000090000000C00000000B051AB030000AA55' 2022-03-09 10:23:02.865 [TRACE] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy//192.168.1.148:6668: Decoded raw payload: 2022-03-09 10:23:02.866 [DEBUG] [.internal.local.handlers.TuyaDecoder] - ebf3cb07f3574a9688z4oy//192.168.1.148:6668: Received MessageWrapper{commandType=HEART_BEAT, content=''} 2022-03-09 10:23:02.949 [TRACE] [.internal.local.handlers.TuyaDecoder] - udpListener: Received encoded '000055AA0000000000000013000000CC00000000D09766676F3369EB10B5E9F132FD802AE33F0A22D663644012FC8D872BCF03D07B64C8DD4401899000716D5170D1858B0B1B576FA1E157538A210ED25F4C31067256F992EF0BB3C9946F6CA8E2E148532E0DBC9A92C3317286EBF238BE57978641CBF9244804CF3DC911CADA0E75770803BF05AC9D8E6CCA4A3B9E5968A55B0DCC37291424EC2B5401F4F50D7EB6A63204AF131AE1CD3F0E4AB9A53DA72F98A8617CB007FF58786669D79C94FB8EA213D482232E3BD3BF5CF00BC08816559FD6A790AEB30000AA55' 2