EspMilightHub new binding for milight limitlessLED and easybulb

sbcarp · January 28, 2020, 12:03am

Hi @matt1. Thanks for your work on this. I have been trying to keep this running for a few months but have had no luck so far. I originally installed this onto a openhabian 2.4 system. It installed fine and I was able to operate my FUT089 light perfectly. After a few days I noticed that the system was non responsive and found repeating errors mentioning the espmilighthub. After restarting openhab it would runn fine again for a few days and then the same repeating error message would cause the system to become non responsive. I figured that it might be a 2.4 issue so I copied my system and did an upgrade. I discovered that I need to install the Paho jar into the Addons folder and did that. After working through the upgrade and after a few days the same repeating message would occur. I then tried uninstalling both the hub and the MQTT broker and install them from scratch as the error seems to complain about the MQTT broker (which is not on the openhab system). Installing the new MQTT broker did clear up some random issues I had with other things but then after installing the hub and after just a few hours the same result occurred. Now I am at a loss as to what to do. Is this something I am doing wrong?
My system is a RPI 3 running 2.5. Here is a sample of the repeating error from the events.log;

2020-01-20 17:38:42.614 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException to ONLINE
2020-01-20 17:38:42.685 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-01-20 17:38:42.696 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException
2020-01-20 17:38:42.705 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-01-20 17:38:42.713 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException to ONLINE
2020-01-20 17:38:42.716 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-01-20 17:38:42.717 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException

and from the openhab.log;

2020-01-27 10:06:01.737 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
2020-01-27 10:06:01.737 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
2020-01-27 10:06:01.704 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
2020-01-27 10:06:01.704 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
2020-01-27 10:06:01.740 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}
org.eclipse.paho.client.mqttv3.MqttException: Connection lost
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_202]
Caused by: java.io.EOFException
	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_202]
	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]
	... 1 more
2020-01-27 10:06:01.708 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
2020-01-27 10:06:01.703 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}
org.eclipse.paho.client.mqttv3.MqttException: Connection lost
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_202]
Caused by: java.io.EOFException
	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_202]
	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]
	... 1 more
2020-01-27 10:06:01.689 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}
org.eclipse.paho.client.mqttv3.MqttException: Connection lost
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_202]
Caused by: java.io.EOFException
	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_202]
	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]
	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]
	... 1 more
2

Thanks Jim

matt1 · January 28, 2020, 12:24am

What broker are you using? Mosquito? Check the log of the broker as it will probably tell you why the connection was lost, maybe too many concurrent users reached or something like that.

swamiller · January 28, 2020, 8:23pm

@sbcarp
I frequently see the same type of connect/disconnect message right after a fresh install or clearing the cache/tmp files. The issue goes away after a restart (or 2).

Oliver_Chapple · January 29, 2020, 9:02pm

Is there a guide on how to do MQTT and this it to openhab to esp8266

sbcarp · January 30, 2020, 1:03pm

Hi @matt1. Yes I am using Mosquito. I will check the logs and get back. Thanks for getting back to me!

sbcarp · January 30, 2020, 1:54pm

Hi @matt1. Ok here are the logs from last week. I did some upgrades since this and I will reinstall the binding and try and get some newer logs. These logs are right after a restart of Openhab. I am trying to get the log times to align here. This is the events.log

2020-01-22 16:16:43.126 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED to INITIALIZING
2020-01-22 16:16:43.511 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2020-01-22 16:16:43.532 [.ItemChannelLinkAddedEvent] - Link 'SonosExerciseRoomControl-sonos:CONNECTAMP:RINCON_5CAAFDE0678801400:control' has been added.
2020-01-22 16:16:43.535 [.ItemChannelLinkAddedEvent] - Link 'Esonos_next-sonos:CONNECTAMP:RINCON_5CAAFDE0678801400:next' has been added.
2020-01-22 16:16:43.540 [.ItemChannelLinkAddedEvent] - Link 'Esonos_previous-sonos:CONNECTAMP:RINCON_5CAAFDE0678801400:previous' has been added.
2020-01-22 16:16:43.543 [.ItemChannelLinkAddedEvent] - Link 'Esonos_Albumart-sonos:CONNECTAMP:RINCON_5CAAFDE0678801400:currentalbumarturl' has been added.
2020-01-22 16:16:43.572 [.ItemChannelLinkAddedEvent] - Link 'Date-ntp:ntp:local:string' has been added.
2020-01-22 16:16:43.587 [.ItemChannelLinkAddedEvent] - Link 'Weather_TemperatureF-weatherunderground:weather:f52f3c8e:current#temperatureF' has been added.
2020-01-22 16:16:43.591 [.ItemChannelLinkAddedEvent] - Link 'ObservationTime0-weatherunderground:weather:f52f3c8e:current#observationTime' has been added.
2020-01-22 16:16:43.594 [.ItemChannelLinkAddedEvent] - Link 'Pressure_Inches-weatherunderground:weather:f52f3c8e:current#pressureIN' has been added.
2020-01-22 16:16:43.598 [.ItemChannelLinkAddedEvent] - Link 'ObservationTime1-weatherunderground:weather:f52f3c8e:forecastTomorrow#forecastTime' has been added.
2020-01-22 16:16:43.599 [.ItemChannelLinkAddedEvent] - Link 'ObservationTime2-weatherunderground:weather:f52f3c8e:forecastDay2#forecastTime' has been added.
2020-01-22 16:16:43.603 [.ItemChannelLinkAddedEvent] - Link 'ObservationTime3-weatherunderground:weather:f52f3c8e:forecastDay3#forecastTime' has been added.
2020-01-22 16:16:43.606 [.ItemChannelLinkAddedEvent] - Link 'Temp_Min0-weatherunderground:weather:f52f3c8e:forecastToday#minTemperatureF' has been added.
2020-01-22 16:16:43.608 [.ItemChannelLinkAddedEvent] - Link 'Temp_Max0-weatherunderground:weather:f52f3c8e:forecastToday#maxTemperatureF' has been added.
2020-01-22 16:16:43.611 [.ItemChannelLinkAddedEvent] - Link 'Temp_Min1-weatherunderground:weather:f52f3c8e:forecastTomorrow#minTemperatureF' has been added.
2020-01-22 16:16:43.613 [.ItemChannelLinkAddedEvent] - Link 'Temp_Max1-weatherunderground:weather:f52f3c8e:forecastTomorrow#maxTemperatureF' has been added.
2020-01-22 16:16:43.614 [.ItemChannelLinkAddedEvent] - Link 'Temp_Min2-weatherunderground:weather:f52f3c8e:forecastDay2#minTemperatureF' has been added.
2020-01-22 16:16:43.616 [.ItemChannelLinkAddedEvent] - Link 'Temp_Max2-weatherunderground:weather:f52f3c8e:forecastDay2#maxTemperatureF' has been added.
2020-01-22 16:16:43.617 [.ItemChannelLinkAddedEvent] - Link 'Temp_Min3-weatherunderground:weather:f52f3c8e:forecastDay3#minTemperatureF' has been added.
2020-01-22 16:16:43.619 [.ItemChannelLinkAddedEvent] - Link 'Temp_Max3-weatherunderground:weather:f52f3c8e:forecastDay3#maxTemperatureF' has been added.
2020-01-22 16:16:43.985 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from INITIALIZING to ONLINE
2020-01-22 16:16:44.021 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2020-01-22 16:16:44.044 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from INITIALIZING to ONLINE
2020-01-22 16:16:54.064 [vent.ItemStateChangedEvent] - espmilighthub_fut089_Auto001_0x5EF1_level changed from NULL to 0
2020-01-22 16:20:23.077 [thome.event.ExtensionEvent] - Extension 'package-expert' has been installed.
2020-01-22 16:21:30.918 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from ONLINE to UNINITIALIZED
2020-01-22 16:21:30.961 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-01-22 16:21:30.968 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to UNINITIALIZED
2020-01-22 16:21:30.993 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED to OFFLINE (COMMUNICATION_ERROR): Currently disconnected from the MQTT broker.
2020-01-22 16:21:34.036 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): Currently disconnected from the MQTT broker. to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-01-22 16:21:39.270 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-01-22 16:21:39.504 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from INITIALIZING to UNINITIALIZED
2020-01-22 16:21:39.529 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED to OFFLINE (COMMUNICATION_ERROR): Currently disconnected from the MQTT broker.
2020-01-22 16:21:39.828 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): Currently disconnected from the MQTT broker. to ONLINE
2020-01-22 16:21:44.562 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-01-22 16:21:44.575 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2020-01-22 16:21:49.324 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-01-22 16:21:49.923 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from INITIALIZING to ONLINE
2020-01-22 16:21:49.959 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2020-01-22 16:21:49.987 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from INITIALIZING to ONLINE
2020-01-22 16:21:50.212 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException
2020-01-22 16:21:50.215 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-01-22 16:21:51.561 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException to ONLINE
2020-01-22 16:21:51.569 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-01-22 16:21:52.685 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException
2020-01-22 16:21:52.691 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-01-22 16:21:54.063 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException to ONLINE
2020-01-22 16:21:54.069 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x5EF1' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-01-22 16:21:54.323 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): MQTT broker connection lost:Connection lost (32109) - java.io.EOFException

This is the broker log;

1579727681: Received PINGREQ from milight-hub-12626265
1579727681: Sending PINGRESP to milight-hub-12626265
1579727687: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727687: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727691: Received PINGREQ from espMilightHub:Auto001
1579727691: Sending PINGRESP to espMilightHub:Auto001
1579727693: Received PINGREQ from mosqsub|18399-jim-Preci
1579727693: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727696: Received PINGREQ from milight-hub-12626265
1579727696: Sending PINGRESP to milight-hub-12626265
1579727696: Received DISCONNECT from espMilightHub:Auto001
1579727699: Received DISCONNECT from 0f63b620-7abd-4eaa-a0f5-fa673a9a5216
1579727702: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727702: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727710: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727710: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727711: Received PINGREQ from milight-hub-12626265
1579727711: Sending PINGRESP to milight-hub-12626265
1579727714: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727714: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727716: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727716: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727716: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727716: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727717: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727717: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727726: Received PINGREQ from milight-hub-12626265
1579727726: Sending PINGRESP to milight-hub-12626265
1579727730: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727730: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727736: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727736: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727738: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727738: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727741: Received PINGREQ from milight-hub-12626265
1579727741: Sending PINGRESP to milight-hub-12626265
1579727746: Saving in-memory database to /var/lib/mosquitto/mosquitto.db.
1579727747: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727747: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727753: Received PINGREQ from mosqsub|18399-jim-Preci
1579727753: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727756: Received PINGREQ from milight-hub-12626265
1579727756: Sending PINGRESP to milight-hub-12626265
1579727762: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727762: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727770: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727770: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727771: Received PINGREQ from milight-hub-12626265
1579727771: Sending PINGRESP to milight-hub-12626265
1579727774: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727774: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727776: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727776: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727776: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727776: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727777: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727777: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727786: Received PINGREQ from milight-hub-12626265
1579727786: Sending PINGRESP to milight-hub-12626265
1579727790: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727790: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727796: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727796: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727798: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727798: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727801: Received PINGREQ from milight-hub-12626265
1579727801: Sending PINGRESP to milight-hub-12626265
1579727803: Socket error on client milight-hub-12626265, disconnecting.
1579727803: New connection from 10.0.0.168 on port 1883.
1579727803: New client connected from 10.0.0.168 as milight-hub-12626265 (c1, k15).
1579727803: Sending CONNACK to milight-hub-12626265 (0)
1579727803: Received SUBSCRIBE from milight-hub-12626265
1579727803: 	milight/commands/+/+/+ (QoS 0)
1579727803: milight-hub-12626265 0 milight/commands/+/+/+
1579727803: Sending SUBACK to milight-hub-12626265
1579727803: New connection from 10.0.0.163 on port 1883.
1579727803: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579727803: Sending CONNACK to espMilightHub:Auto001 (0)
1579727808: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727808: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727813: Received PINGREQ from mosqsub|18399-jim-Preci
1579727813: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727813: Received SUBSCRIBE from espMilightHub:Auto001
1579727813: 	milight/states/# (QoS 1)
1579727813: espMilightHub:Auto001 1 milight/states/#
1579727813: Sending SUBACK to espMilightHub:Auto001
1579727813: Sending PUBLISH to espMilightHub:Auto001 (d0, q0, r1, m0, 'milight/states/0x5EF/fut089/1', ... (64 bytes))
1579727813: Sending PUBLISH to espMilightHub:Auto001 (d0, q0, r1, m0, 'milight/states/0x5EF/rgb_cct/1', ... (15 bytes))
1579727818: Received PINGREQ from milight-hub-12626265
1579727818: Sending PINGRESP to milight-hub-12626265
1579727822: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727822: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727828: Received PINGREQ from espMilightHub:Auto001
1579727828: Sending PINGRESP to espMilightHub:Auto001
1579727830: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727830: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727833: Received PINGREQ from milight-hub-12626265
1579727833: Sending PINGRESP to milight-hub-12626265
1579727834: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727834: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727836: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727836: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727836: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727836: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727837: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727837: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727843: Received PINGREQ from espMilightHub:Auto001
1579727843: Sending PINGRESP to espMilightHub:Auto001
1579727848: Received PINGREQ from milight-hub-12626265
1579727848: Sending PINGRESP to milight-hub-12626265
1579727850: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727850: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727856: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727856: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727858: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727858: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727858: Received PINGREQ from espMilightHub:Auto001
1579727858: Sending PINGRESP to espMilightHub:Auto001
1579727863: Received PINGREQ from milight-hub-12626265
1579727863: Sending PINGRESP to milight-hub-12626265
1579727868: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727868: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727873: Received PINGREQ from mosqsub|18399-jim-Preci
1579727873: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727874: Received PINGREQ from espMilightHub:Auto001
1579727874: Sending PINGRESP to espMilightHub:Auto001
1579727878: Received PINGREQ from milight-hub-12626265
1579727878: Sending PINGRESP to milight-hub-12626265
1579727882: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727882: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727889: Received PINGREQ from espMilightHub:Auto001
1579727889: Sending PINGRESP to espMilightHub:Auto001
1579727890: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727890: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727893: Received PINGREQ from milight-hub-12626265
1579727893: Sending PINGRESP to milight-hub-12626265
1579727894: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727894: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727896: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727896: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727896: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727896: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727897: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727897: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727904: Received PINGREQ from espMilightHub:Auto001
1579727904: Sending PINGRESP to espMilightHub:Auto001
1579727908: Received PINGREQ from milight-hub-12626265
1579727908: Sending PINGRESP to milight-hub-12626265
1579727910: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727910: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727916: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727916: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727918: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727918: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727919: Received PINGREQ from espMilightHub:Auto001
1579727919: Sending PINGRESP to espMilightHub:Auto001
1579727923: Received PINGREQ from milight-hub-12626265
1579727923: Sending PINGRESP to milight-hub-12626265
1579727928: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727928: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727933: Received PINGREQ from mosqsub|18399-jim-Preci
1579727933: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727934: Received PINGREQ from espMilightHub:Auto001
1579727934: Sending PINGRESP to espMilightHub:Auto001
1579727938: Received PINGREQ from milight-hub-12626265
1579727938: Sending PINGRESP to milight-hub-12626265
1579727942: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727942: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579727949: Received PINGREQ from espMilightHub:Auto001
1579727949: Sending PINGRESP to espMilightHub:Auto001
1579727950: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727950: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579727953: Received PINGREQ from milight-hub-12626265
1579727953: Sending PINGRESP to milight-hub-12626265
1579727954: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727954: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579727956: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727956: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579727957: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727957: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579727957: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727957: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579727964: Received PINGREQ from espMilightHub:Auto001
1579727964: Sending PINGRESP to espMilightHub:Auto001
1579727968: Received PINGREQ from milight-hub-12626265
1579727968: Sending PINGRESP to milight-hub-12626265
1579727970: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727970: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579727976: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727976: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579727978: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727978: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579727979: Received PINGREQ from espMilightHub:Auto001
1579727979: Sending PINGRESP to espMilightHub:Auto001
1579727983: Received PINGREQ from milight-hub-12626265
1579727983: Sending PINGRESP to milight-hub-12626265
1579727988: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727988: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579727993: Received PINGREQ from mosqsub|18399-jim-Preci
1579727993: Sending PINGRESP to mosqsub|18399-jim-Preci
1579727994: Received PINGREQ from espMilightHub:Auto001
1579727994: Sending PINGRESP to espMilightHub:Auto001
1579727998: Received PINGREQ from milight-hub-12626265
1579727998: Sending PINGRESP to milight-hub-12626265
1579728002: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579728002: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579728009: Received PINGREQ from espMilightHub:Auto001
1579728009: Sending PINGRESP to espMilightHub:Auto001
1579728010: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579728010: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579728013: Received PINGREQ from milight-hub-12626265
1579728013: Sending PINGRESP to milight-hub-12626265
1579728014: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579728014: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579728016: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579728016: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579728017: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579728017: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579728017: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579728017: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579728024: Received PINGREQ from espMilightHub:Auto001
1579728024: Sending PINGRESP to espMilightHub:Auto001
1579728028: Received PINGREQ from milight-hub-12626265
1579728028: Sending PINGRESP to milight-hub-12626265
1579728030: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579728030: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579728036: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579728036: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579728038: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579728038: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579728039: Received PINGREQ from espMilightHub:Auto001
1579728039: Sending PINGRESP to espMilightHub:Auto001
1579728043: Received PINGREQ from milight-hub-12626265
1579728043: Sending PINGRESP to milight-hub-12626265
1579728048: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579728048: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579728052: Received PINGREQ from mosqsub|18399-jim-Preci
1579728052: Sending PINGRESP to mosqsub|18399-jim-Preci
1579728054: Received PINGREQ from espMilightHub:Auto001
1579728054: Sending PINGRESP to espMilightHub:Auto001
1579728058: Received PINGREQ from milight-hub-12626265
1579728058: Sending PINGRESP to milight-hub-12626265
1579728062: Received PINGREQ from 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579728062: Sending PINGRESP to 7kqRH^L9=h7cc5dBpHEeb2w2e3R7qCDx6vB>K^GXN>2qDgtto0`YkNKXQm_9qdrw
1579728069: Received PINGREQ from espMilightHub:Auto001
1579728069: Sending PINGRESP to espMilightHub:Auto001
1579728070: Received PINGREQ from a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579728070: Sending PINGRESP to a;<liSKwQ=`etK`[K7t>V6Vgcua[`ZY8UUt5h64ADTfxo>Z1EEx[KU:6Rkar<22a
1579728073: Received PINGREQ from milight-hub-12626265
1579728073: Sending PINGRESP to milight-hub-12626265
1579728074: Received PINGREQ from Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579728074: Sending PINGRESP to Gff7mZ8qnnpTlBV9WUTck^iD@Q=<Cxm[fKRJuZBkOrFCt\<CrPvdoVh6ge32Up]2
1579728076: Received PINGREQ from 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579728076: Sending PINGRESP to 26LxPNZ_G`b34nFv63QuY0kGUn9jVVlXL?Gl]qSeI<XMkn:qaK]1;OH`5BB[h5:<
1579728077: Received PINGREQ from EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579728077: Sending PINGRESP to EBhbsB>C?fPjKJR3e64`E=8JOJewO`sTrc>luL7t:GeUb>I>4=oJJwTY8AHWqB3j
1579728078: Received PINGREQ from uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579728078: Sending PINGRESP to uAMbMD]G\:]58v3=:rGE`k^hlvGd@:E<;SnXgCp:=T?EACB;uYPM<6<x3CTCMY@H
1579728084: Received PINGREQ from espMilightHub:Auto001
1579728084: Sending PINGRESP to espMilightHub:Auto001
1579728088: Received PINGREQ from milight-hub-12626265
1579728088: Sending PINGRESP to milight-hub-12626265
1579728090: Received PINGREQ from 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579728090: Sending PINGRESP to 3nq[8XeEleK4h]?TwPqsF5bI8=MFWM^JBFu;nR@Qw[Uf0UAgesa2hJ;pHXvoe\@x
1579728091: Received DISCONNECT from espMilightHub:Auto001
1579728096: Received PINGREQ from lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579728096: Sending PINGRESP to lrc0j8Sw0Cg47mf@mlPH?NPmPq7Hbs1VleV]]j\^4JRuwx<kkLsjjJN2CVJe@;Bl
1579728098: Received PINGREQ from rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579728098: Sending PINGRESP to rvsXO:0MVFsF[t4GneI]oTE>mBmYn5HXkB7A=7NcM8ih3m6bI?F?T[>H^2aSpj2b
1579728099: Socket error on client milight-hub-12626265, disconnecting.
1579728099: New connection from 10.0.0.168 on port 1883.
1579728099: New client connected from 10.0.0.168 as milight-hub-12626265 (c1, k15).
1579728099: Sending CONNACK to milight-hub-12626265 (0)
1579728099: Received SUBSCRIBE from milight-hub-12626265
1579728099: 	milight/commands/+/+/+ (QoS 0)
1579728099: milight-hub-12626265 0 milight/commands/+/+/+
1579728099: Sending SUBACK to milight-hub-12626265
1579728099: New connection from 10.0.0.163 on port 1883.
1579728099: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728099: Sending CONNACK to espMilightHub:Auto001 (0)
1579728108: Received PINGREQ from `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579728108: Sending PINGRESP to `YQ5Jmc>wu:et7HVCB85]Vp`lAL3<_p]x8b9eLGS8B?39GYLZaAwx8^k91_FPVsO
1579728109: Socket error on client milight-hub-12626265, disconnecting.
1579728109: New connection from 10.0.0.168 on port 1883.
1579728109: New client connected from 10.0.0.168 as milight-hub-12626265 (c1, k15).
1579728109: Sending CONNACK to milight-hub-12626265 (0)
1579728109: Received SUBSCRIBE from milight-hub-12626265
1579728109: 	milight/commands/+/+/+ (QoS 0)
1579728109: milight-hub-12626265 0 milight/commands/+/+/+
1579728109: Sending SUBACK to milight-hub-12626265
1579728109: New connection from 10.0.0.163 on port 1883.
1579728109: New connection from 10.0.0.163 on port 1883.
1579728109: Client espMilightHub:Auto001 already connected, closing old connection.
1579728109: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728109: Sending CONNACK to espMilightHub:Auto001 (0)
1579728110: Client espMilightHub:Auto001 already connected, closing old connection.
1579728110: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728110: Sending CONNACK to espMilightHub:Auto001 (0)
1579728110: New connection from 10.0.0.163 on port 1883.
1579728111: New connection from 10.0.0.163 on port 1883.
1579728111: Client espMilightHub:Auto001 already connected, closing old connection.
1579728111: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728111: Sending CONNACK to espMilightHub:Auto001 (0)
1579728111: Client espMilightHub:Auto001 already connected, closing old connection.
1579728111: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728111: Sending CONNACK to espMilightHub:Auto001 (0)
1579728112: New connection from 10.0.0.163 on port 1883.
1579728112: New connection from 10.0.0.163 on port 1883.
1579728112: Client espMilightHub:Auto001 already connected, closing old connection.
1579728112: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728112: Sending CONNACK to espMilightHub:Auto001 (0)
1579728112: Client espMilightHub:Auto001 already connected, closing old connection.
1579728112: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728112: Sending CONNACK to espMilightHub:Auto001 (0)
1579728113: Received PINGREQ from mosqsub|18399-jim-Preci
1579728113: Sending PINGRESP to mosqsub|18399-jim-Preci
1579728113: New connection from 10.0.0.163 on port 1883.
1579728113: New connection from 10.0.0.163 on port 1883.
1579728114: Client espMilightHub:Auto001 already connected, closing old connection.
1579728114: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728114: Sending CONNACK to espMilightHub:Auto001 (0)
1579728114: Client espMilightHub:Auto001 already connected, closing old connection.
1579728114: New client connected from 10.0.0.163 as espMilightHub:Auto001 (c0, k15).
1579728114: Sending CONNACK to espMilightHub:Auto001 (0)

Thanks again for helping!

Bl00d_b0b · February 27, 2020, 11:40pm

Hello,

Just installed plugin espmilighthub-2019-12-28.zip. using openHAB 2.5.2 Release Build

And I get two problems.

I receive error: [b.handler.EspMilightHubBridgeHandler] - Can not reach the espMilightHub directly, check you have entered the correct HUB_IP in the Things setup. But I am sure IP is OK, and reachable, no firewall. Maybe this problem already solved?
then I set my color dimmer to the right possition, then I receiving HTTP ERROR 400: [rest.core.internal.item.ItemResource] - Received HTTP POST request at ‘items/Milight_3364_A_Hue’ with an invalid status value ‘360,46,44’.

Ed

matt1 · February 28, 2020, 2:11am

What version firmware are you using and does anything work if you leave out the IP address and manually setup the firmwares settings? Possibly the firmware has made changes recently. Have you put
A password on the config of the firmware that may also be the cause of the first error?

Bl00d_b0b · February 28, 2020, 10:19am

Hello,

Plugin works using mqtt. I do not use password for web site, my firmware version is 1.10.5 (nodemcuv2). But I see error message about problem with direct connect miLightHub. Any help?

I run sniffer, and I see openhab2 is connecting to my mylighthub.

PUT /settings HTTP/1.1
User-Agent: Java/1.8.0_201
Host: 192.168.10.63
Accept: text/html, image/gif, image/jpeg, *; q=.2, /; q=.2
Connection: keep-alive
Content-Length: 274
{“mqtt_topic_pattern”:“milight/commands/:device_id/:device_type/:group_id”,“mqtt_update_topic_pattern”:"",“mqtt_state_topic_pattern”:“milight/states/:device_id/:device_type/:group_id”,“group_state_fields”:[“state”,“level”,“hue”,“saturation”,“mode”,“color_temp”,“bulb_mode”]}

HTTP/1.1 200 OK
Content-Type: application/json
Content-Length: 16
Connection: close
{“success”:true}

My assumption somewhere is mistake in a plugin code. as the device is reply to plugin with success status.

FEATURE REQUEST: for security reason it wold be good to implement login/password for configuring milighthub in next update.

Ed

Oliver_Chapple · March 3, 2020, 10:09pm

hi how to I add the blinding for esp to openhab to pick the lights up automatic

matt1 · March 3, 2020, 11:19pm

Interesting you get the error but your sniffer shows it has worked. Just ignore it and you can remove the IP fully from the bindings setup, it is only for the initial setup which needs to be run once. For this reason I am not keen to do coding to support logins as really if people can follow setup instructions the feature is not needed. At any time the firmware could change and I don’t wish to waste time on a non important feature.

BTW not having a login set is a huge security flaw as people can get your mqtt password with the API, not sure if this is still an issue in newer firmwares.

See the readme.

Bl00d_b0b · March 4, 2020, 1:48pm

Hello,

OK, I agree, if IP is used only for the first configuration, then it’s not required. But I think It wold be good to think about this new feature.

I have Remote control and wall switch in a room, both managing same bulbs. but remote control & wall switch have different ID’s. I think it should be implemented as an alias for one single device. As I want to turn on with remote, turn off with switch and see updated status.

Any Idea?

Ed

matt1 · March 4, 2020, 11:23pm

You can handle that in one of two ways off the top of my head.

Link two channels to the same Openhab control, never done it but it should work if it does this would be the easiest to setup and manage.
Use the bridge feature of mosquito to copy the mqtt messages from one remote to the others remote topics.

matt1 · March 5, 2020, 11:01am

New build now released to fix this bug. Thanks for reporting it.

Knappe_Peter · March 7, 2020, 7:59pm

Hello,
i have installed the last release espmilighthub-2020-03-05.zip on Openhab 2.5.2
I work with firmware 1.10.4 (nodemcuv2).
Dimming works, but when I try to set the color temperature I get the following error in the log:

2020-03-07 20:36:30.885 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.openhab.binding.espmilighthub.handler.EspMilightHubHandler@1d8c661': For input string: "98.0"
java.lang.NumberFormatException: For input string: "98.0"
	at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[?:1.8.0_222]
	at java.lang.Integer.parseInt(Integer.java:580) ~[?:1.8.0_222]
	at java.lang.Integer.valueOf(Integer.java:766) ~[?:1.8.0_222]
	at org.openhab.binding.espmilighthub.handler.EspMilightHubHandler.handleCommand(EspMilightHubHandler.java:137) ~[?:?]
	at sun.reflect.GeneratedMethodAccessor106.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
	at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [bundleFile:?]
	at com.sun.proxy.$Proxy614.handleCommand(Unknown Source) [?:?]
	at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:74) [bundleFile:?]
	at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:48) [bundleFile:?]
	at sun.reflect.GeneratedMethodAccessor105.invoke(Unknown Source) ~[?:?]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_222]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_222]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_222]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]

Do you have any ideas what going wrong?

Peter

matt1 · March 7, 2020, 10:46pm

It is the decimal point in the number causing issues. Try the latest firmware as I have no issues on that version 1.10.5

Knappe_Peter · March 8, 2020, 12:50pm

Upgrade the firmware fixes the problem.
Many thanks for your excellent work.

Peter

matt1 · March 11, 2020, 8:08am

I just uploaded a new build which may have a possible fix for the endless reconnecting that happens every now and then with openhab 2.5.x
Please report if it fixes or does not fix the issue.

A restart of openhab stops it from occurring if this change does not.

No other changes besides this.

swamiller · March 13, 2020, 1:36am

I appreciate your work! After installing the latest version of the binding and clearing the cache, the connect/reconnect issues still exists.

Here is an excerpt from my log. The binding will continue to repeat the following until restart:

2020-03-12 20:31:58.892 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.

2020-03-12 20:31:58.892 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.893 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}

org.eclipse.paho.client.mqttv3.MqttException: Connection lost

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]

	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_242]

Caused by: java.io.EOFException

	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_242]

	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]

	... 1 more

2020-03-12 20:31:58.896 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.927 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.

2020-03-12 20:31:58.928 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.928 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}

org.eclipse.paho.client.mqttv3.MqttException: Connection lost

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]

	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_242]

Caused by: java.io.EOFException

	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_242]

	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]

	... 1 more

2020-03-12 20:31:58.934 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.943 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.

2020-03-12 20:31:58.944 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.946 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.

2020-03-12 20:31:58.947 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.947 [ERROR] [b.handler.EspMilightHubBridgeHandler] - MQTT connection has been lost, cause reported is:{}

org.eclipse.paho.client.mqttv3.MqttException: Connection lost

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:190) [bundleFile:?]

	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_242]

Caused by: java.io.EOFException

	at java.io.DataInputStream.readByte(DataInputStream.java:267) ~[?:1.8.0_242]

	at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:92) ~[?:?]

	at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:137) ~[?:?]

	... 1 more

2020-03-12 20:31:58.951 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

2020-03-12 20:31:58.970 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.

2020-03-12 20:31:58.972 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler EspMilightHubBridgeHandler tried updating the thing status although the handler was already disposed.

==> /var/log/openhab2/events.log <==

2020-03-12 20:31:59.032 [hingStatusInfoChangedEvent] - 'zwave:device:15b9248bced:node21' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2020-03-12 20:31:59.033 [hingStatusInfoChangedEvent] - 'zwave:device:15b9248bced:node30' changed from ONLINE: Node initialising: PING to UNINITIALIZED

matt1 · March 13, 2020, 2:11am

Interesting that at the same time another binding is having a similar/same issue. I suspect this is a Openhab core issue as it is 100% reliable up until the core does a warm restart when an item file is saved for example. Doing a restart of Openhab solves the issue. At least this is what I am finding as it has only happened a few times for me.

Thanks for reporting it still happens, will take another look to add some logging in to track this down.