Dear Guys,
usually I´m able to find any issues by my self, but in MiLight Binding it looks like I have an issue which I can´t find out by my self.
The MiLight binding was working almost perfect for a few weeks now but after updating to the latest 2.2.0 version I got a issue, cause the binding is spamming full the logs.
The binding is still working with my bridge and LED´s but I get this Logs all 2-3 sec. in my log:
20:16:16.940 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:16.951 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:18.759 [INFO ] [smarthome.event.ItemStateChangedEvent] - Wohnzimmer_Heizung_SET_TEMPERATURE changed from 22 to 6
20:16:18.769 [INFO ] [smarthome.event.ItemStateChangedEvent] - Wohnzimmer_Heizung_ACTUALHUMIDITY changed from 31 to 31.25
20:16:18.863 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:19.392 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_W changed from 253 to 265
20:16:19.409 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_counts changed from 128461508 to 128461510
20:16:19.424 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 85641.0000 to 85641.0078
20:16:21.092 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:21.950 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:21.958 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:26.998 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:27.006 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:29.406 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_W changed from 265 to 274
20:16:29.439 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_counts changed from 128461510 to 128461512
20:16:32.005 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:32.015 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:37.015 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:37.027 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:39.416 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_W changed from 274 to 270
20:16:39.427 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_counts changed from 128461512 to 128461514
20:16:41.092 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:41.103 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:42.026 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:42.037 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:47.037 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:47.048 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:49.406 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_W changed from 270 to 259
20:16:49.417 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_counts changed from 128461514 to 128461516
20:16:49.436 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 85641.0078 to 85641.0156
20:16:51.404 [INFO ] [smarthome.event.ItemStateChangedEvent] - Miete_Kueche_Heizung_ACTUALTEMP changed from 12.5 to 12.4000000000000003552713678800500929355621337890625
20:16:52.048 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:52.058 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:57.058 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:16:57.067 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:16:59.411 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_W changed from 259 to 261
20:16:59.432 [INFO ] [smarthome.event.ItemStateChangedEvent] - Stromzaeler_counts changed from 128461516 to 128461518
20:17:01.103 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:17:01.111 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:17:02.067 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
20:17:02.076 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
20:17:07.075 [DEBUG] [.milight.internal.protocol.QueuedSend] - Sent packet 'D0 00 00 00 02 7D 00 ’ to bridge 192.168.20.98
First I thought it´s from the Keep Alive interval but after changing this to a higher period the log comes still all 2-3 sec.
When I change log:set TRACE smarthome.event I get the info that the Thing updates the timestamp if I note it right.
2017-12-17 20:40:30.403 [me.event.ThingUpdatedEvent] - Received event of type ‘ThingUpdatedEvent’ under the topic ‘smarthome/things/milight:bridgeV6:MiLight/updated’ with payload: ‘[{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539625599”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:},{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539620474”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:}]’
2017-12-17 20:40:30.510 [me.event.ThingUpdatedEvent] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
2017-12-17 20:40:34.241 [me.event.ThingUpdatedEvent] - Received event of type ‘ThingUpdatedEvent’ under the topic ‘smarthome/things/milight:bridgeV6:MiLight/updated’ with payload: ‘[{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539630520”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:},{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539625599”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:}]’
2017-12-17 20:40:34.367 [me.event.ThingUpdatedEvent] - Thing ‘milight:bridgeV6:MiLight’ has been updated.
2017-12-17 20:40:35.409 [me.event.ThingUpdatedEvent] - Received event of type ‘ThingUpdatedEvent’ under the topic ‘smarthome/things/milight:bridgeV6:MiLight/updated’ with payload: ‘[{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539634266”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:},{“label”:“iBox/iBox2”,“configuration”:{“REFRESH_IN_SEC”:20,“REPEAT”:2,“WAIT_BETWEEN_COMMANDS”:100,“ID”:“F0FE6B62CA3A”,“PASSWORD_BYTE_1”:0,“ADDR”:“192.168.20.98”,“PASSWORD_BYTE_2”:0},“properties”:{“sessionid”:“7D 00”,“sessionid_last_refresh”:“1513539630520”},“UID”:“milight:bridgeV6:MiLight”,“thingTypeUID”:“milight:bridgeV6”,“channels”:}]’
Has somebody an idea, what it can be and how to fix that?
Thanks
Jan