Here is a release candidate for testing V5.2.0 that includes:
OpenHAB Integratuib
FHEM Integration
Broadlink Integration
In 5.0.0, colour UDP URLs not issued to milight hub
[5.1.0] Harmony commands not being split into on/dim
non-dimmable device
HB[5.1.0] Fibaro HomeCenter2: Devices "Build Item" not working
Updated harmony library.
Timing fixes for UPNP
OK - i did an Fhem Test
After Installation i could define an Fhem Server with a room named HaBridge
The Definition of the Fhem Server was simple (it was on the same Raspi Server than HaBridge)i only inserted the Name of the Server "raspi-cam" the IP Adress "192.168.178.26" the Port "8083".
After that i saved and at the other tab (FhemDevices) HaBridge created an Fhem Device with the same Name than the Fhem Servers Name "raspi-cam". No other Information and it is not possible to build an Device.
Sorry that doesnt work.
@essera Glad you are trying this out. With FHEM I need someone to test it for me as I do not have a FHEM. Please turn on DEBUG for the fhem classes and post results here. Thanks
OK - here some Log entries:
01-03-2018 23:54:00.782 | DEBUG | Get FHEM devices | com.bwssystems.HABridge.devicemanagmeent.DeviceResource
-- | -- | -- | --
01-03-2018 23:54:00.782 | DEBUG | consolidating devices for FHEM | com.bwssystems.HABridge.plugins.fhem.FHEMHome
01-03-2018 23:54:00.783 | DEBUG | doHttpRequest with url: http://192.168.178.26:8083/fhem?cmd=jsonlist2%20room=HaBridge with http command: GET with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-03-2018 23:54:00.784 | DEBUG | Making outbound call in doHttpRequest: <<
01-03-2018 23:54:00.848 | DEBUG | GET execute (0) on URL responded: 200
01-03-2018 23:54:00.850 | DEBUG | Successfull response - The http response is <<<
As described above - i only defined the room "HaBridge" . It i got an entry in Fhem device but it was not possible vfor me to generate an Habridge device from that very simple list (missing Name of device on that list and other Things...)
What I really need is the raw log that is the output of the java program on the command line or syslog.
The Output is too long to post it here. Is it possible to send an Email ?
Sure, send the file to [email protected].
I found the issues you see, will be fixed in RC2
Thats great - will test it when it comes up.
New JAR to test!
RC3 works great with echo dot gen2.
Thanks for the test color button! :)
I use milight v6 with my own python script, so I hardwired ${color.r} as color to pass to milight as test and works great.
Tried the build button with Fibaro Devices and can confirm all is working fine.
Alexa is seeing the bulb as a colour bulb but without colour capabilities. I have Milight's with a v2 hub. The test colour button does change the colour correctly!
Anyone got their Echo Gen2 to find their devices? Running the RC3 on a raspberry pi, no luck no matter what UPNP Send Delay is set to. My (usually disconected) dot finds them with no problem when connected for testing.
Fhem doesnt work麓.Neither the fetched name of the device was korrekt nor the HTTP request to Switch the device. I try to send you samples by mail....
Hi,
ha-bridge can not find any scenes provided by my fibaro hc2.
Yes I get the following error:
Get Fibaro Scenes Error: undefined with status: Server Error - 500
Rules for Testing: I need DEBUG turned on for your particular system com.bwssystems.HABridge.plugins.
when "On with First Dim" is false
and device state off OR on
and brightness command sent from harmony:
habridge seems to be sending on (domoticz), dim (passthru), then on (domoticz) again. Only posting one log for off/on, since identical.
01-09-2018 17:03:47.580 | DEBUG | HueMulator PUT called on api/* with request <<>>, and body <<<{"xy":[0,0],"on":true,"bri":204}>>> | com.bwssystems.HABridge.hue.HueMulator
-- | -- | -- | --
01-09-2018 17:03:47.584 | DEBUG | hue state change requested: 8d93f0339d524fd28d1f13f44a2eeba3 from 192.168.2.10 body: {"xy":[0,0],"on":true,"bri":204} | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.586 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.589 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.738 | DEBUG | Decode Json for url items: [{"item":"{\"ipAddress\":\"192.168.2.230\",\"deviceId\":\"7\",\"hueName\":\"Philips Hue\"}","type":"hueDevice"},{"item":"{\"ipAddress\":\"192.168.2.230\",\"deviceId\":\"8\",\"hueName\":\"Philips Hue\"}","type":"hueDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.741 | DEBUG | Calling Home device handler for type : hueDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.854 | DEBUG | Calling Home device handler for type : hueDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.991 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:47.994 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:03:48.043 | DEBUG | Response is in error: null
will test additional scenarios and add to separate post.
when "On with First Dim" is true
and device state is off OR on
and brightness command sent from harmony:
habridge seems to send "on" 3 times. Only posting one log for off/on, since identical.
01-09-2018 17:13:13.389 | DEBUG | HueMulator PUT called on api/* with request <<>>, and body <<<{"xy":[0,0],"on":true,"bri":204}>>> | com.bwssystems.HABridge.hue.HueMulator
-- | -- | -- | --
01-09-2018 17:13:13.390 | DEBUG | hue state change requested: 8d93f0339d524fd28d1f13f44a2eeba3 from 192.168.2.10 body: {"xy":[0,0],"on":true,"bri":204} | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.391 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.392 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.393 | DEBUG | doHttpRequest with url: http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On with http command: null with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.393 | DEBUG | Making outbound call in doHttpRequest: <<
01-09-2018 17:13:13.432 | DEBUG | GET execute (0) on URL responded: 200 | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.433 | DEBUG | Successfull response - The http response is <<<{ "status" : "OK", "title" : "SwitchLight" } >>> | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.536 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.537 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.538 | DEBUG | doHttpRequest with url: http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On with http command: null with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.538 | DEBUG | Making outbound call in doHttpRequest: <<
01-09-2018 17:13:13.579 | DEBUG | GET execute (0) on URL responded: 200 | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.581 | DEBUG | Successfull response - The http response is <<<{ "status" : "OK", "title" : "SwitchLight" } >>> | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.684 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.684 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-09-2018 17:13:13.685 | DEBUG | doHttpRequest with url: http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On with http command: null with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.686 | DEBUG | Making outbound call in doHttpRequest: <<
01-09-2018 17:13:13.731 | DEBUG | GET execute (0) on URL responded: 200 | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.734 | DEBUG | Successfull response - The http response is <<<{ "status" : "OK", "title" : "SwitchLight" } >>> | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-09-2018 17:13:13.741 | DEBUG | Response is in error: null | com.bwssystems.HABridge.hue.HueMulator
@gohamstergo Yes, that is correct. I also implemented the code for sending multiple commands if the body contained any item for on/off, brightness and color. The body that was sent to the ha-bridge above, has all three.
Oh? Well, a symptom I'm having with 3 commands being sent is that the domoticz command being sent actually cycles a script. So, with 3 identical commands being sent, the script is being cycled 3 times.
Idea:
If "On with first dim" is selected and Harmony sends a command with xy, on, and bri, then it would simply be treated as a single "on" command. After that, the commands would have to be broken down. So, when the next xy, on, and bri is sent, habridge knows the state for this device is already 'on' so that can be ignored, if 'xy' is 0,0, that can be ignored, the 'bri' could be compared to previous 'bri' and if different, then process as "dim" (since it can be assumed the up/down rocker switch is being pressed on harmony remote or in-app) and if identical process as another intentional "on"command (since it can be assumed that the user pressed the device 'on' button again).
Thoughts?
Requestet screenshot added in comment obove about fibaro scenes not showing up
@gohamstergo That makes sense when that selection is made. Will update.
@xPRozAcx As in my previous request I need you to turn on DBUG for a few things. The screenshot tells me very little. Rules for Testing: I need DEBUG turned on for your particular system com.bwssystems.HABridge.plugins. (fibaro, FHEM, etc) and com.bwssystems.HABridge.plugins.http so that you can post your command line log output.
@bwssytems I understood what you need but this is all what I can get with DEBUG on the required plugins
@xPRozAcx Please select the fibaro scene and device tabs and then post the command line output of your log.
I found a minor thing while playing around with the new cool OpenHAB integration. If you set up an OpenHAB instance and only fill in the IP/Hostname and leave the port blank (as there is a default value of 8080), and then create a new item from the OpenHAB tab, it does not add the 8080 port, but only adds the colon behind the IP/Hostname.
It is a pleasure for me to read that you integrate FHEM support, since all other "famous" homeautomation tools are already on board. Actual i didnt find a Wiki or something to test RC3 against my FHEM installation. How can it be integrated ? How does it interact with FHEM ? And what will be possible ?
BRs
Stefan
@m4rcu5 Thanks for that find, will default it if not given.
After selecting fibaro device and scene tab I get the following debug log
@xPRozAcx Scratch that last comment. I see it is on, but no output happened. Please let me take a look.
@bwssytems :)
Thank you
I sent an email with debug Log Infos during Fhem Device creation to you....
New Release candidate 5.2.0RC4, download from link at top post.
Fixed RC test issues for:
FHEM device decode
on for first dim
default OpoenHab Port
Fibaro request debugging enhancement
i think we are getting close! so, when state is on and a new bri level is called, habridge does detect it properly, but still runs "on" which is the domoticz command. Here's log and at bottom is screenshot of my on/dim/off just for clarity.
01-12-2018 16:36:28.884 | DEBUG | HueMulator PUT called on api/* with request <<>>, and body <<<{"xy":[0,0],"on":true,"bri":229}>>> | com.bwssystems.HABridge.hue.HueMulator
-- | -- | -- | --
01-12-2018 16:36:28.885 | DEBUG | hue state change requested: 8d93f0339d524fd28d1f13f44a2eeba3 from 192.168.2.10 body: {"xy":[0,0],"on":true,"bri":229} | com.bwssystems.HABridge.hue.HueMulator
01-12-2018 16:36:28.886 | DEBUG | Calling dim as requested. | com.bwssystems.HABridge.hue.HueMulator
01-12-2018 16:36:28.887 | DEBUG | Decode Json for url items: [{"item":"http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On","type":"domoticzDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-12-2018 16:36:28.888 | DEBUG | Calling Home device handler for type : domoticzDevice | com.bwssystems.HABridge.hue.HueMulator
01-12-2018 16:36:28.889 | DEBUG | doHttpRequest with url: http://192.168.2.240:9000/json.htm?type=command¶m=switchlight&idx=143&switchcmd=On with http command: null with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-12-2018 16:36:28.889 | DEBUG | Making outbound call in doHttpRequest: <<
01-12-2018 16:36:28.944 | DEBUG | GET execute (0) on URL responded: 200 | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-12-2018 16:36:28.946 | DEBUG | Successfull response - The http response is <<<{ "status" : "OK", "title" : "SwitchLight" } >>> | com.bwssystems.HABridge.plugins.http.HTTPHandler

After installing RC4 and setting com.bwssystems.HABridge.plugins.fibaro and com.bwssystems.HABridge.plugins.fibaro.http to DEBUG level I get the same poor informations for you.
But when I set com.bwssystems.HABridge.plugins.fibaro.FibaroInfo to DEBUG it seems he ha-bridge is reading my scenes but they will not show up @ fibaro scenes and I'm getting error 500 again.
I have to split up the screenshots cause there is to much on the screen :)
@xPRozAcx just copy/paste like i am. it should save the formatting.
edit: as recommended below, will post terminal output to file next time :)
I'd prefer if you took the command line output and post it as a file link. That would be the best.
@xPRozAcx if you could post your ha-bridge.conf file as well
@gohamstergo Gotcha on the on+bri command
The second screen are the scenes from fibaro that are accesible by the habridge user
Is there already a way, to bypass the ~5 second timeout on alexa? I would like so send multiple commands with timeouts, which works, but alexa always returns a "device is not responding".
btw. great software, thanks :)
A couple of issues
With 5.2 I had problems to keep actual state for lights in Harmony. After a few sec after turning on a light, it reverted to "off" on the remote. -Light is still on.
-Figured out that Harmony had problems reading the "Color items", which I don't have/use. -By setting the color item to a dummy MQTT message (topic "ignore", value "ignore"), the remote is able to keep the state.
Would it be possible to ignore the color items when not configured? -And even be able to delete configured items properly?
Second issue is related to bridgeupdatestate.
When sending both "on" and "bri", then only "bri" changes. Response from PUT is showing ok for both, but "on" is not updated. -If I send "on" only, then it gets updated.
Update: Observed that setting the "bri" will always set "on" to true. -Is this by design? -If so, I guess my challenge with setting both is answered.. -The challenge with my lights is that brightness 0 means light off (KNX with LogicMachine as counterpart here)
@xPRozAcx I still need your command line logs (not the screen copies) to b posted. Also, I'm not sure what you mean by ha bridge user.
@sneaker8650 There is no api to change Alexa command timeout. The habridge will always finish the command so it is more of an annoyance.
@spiff972 Will take a look at your issues. There is a new feature in 5.2.0 that will set a device to only do on at certain times without sending the dim or color. Also, It should be good if the color url is empty, it should not be sent.
@gohamstergo I believe the logic is doing what you requested. Only the dim request was sent in your request and no on URL was sent.
Edit: Never mind, I found the logic error.... stupid check for onFirstDim was left in.
@spiff972 For the harmony controlled items, you will need to use the setting "On with First Dim (If the device is not on in the ha-bridge state, it will send on instead of the dim.)" in the device edit screen. The next release will handle this properly.
Thanks for your feedback. I managed to get "around" the issue in my script in LM, basically send "on":false when needed, and use "bri" else. My lights will turn on when brightness is >0.
Got it fully working now, only issue is color (and maybe dim??) where I don't need it.
I'm new to your tool.... But, I would really like the Harmony hub to recognize each light as what it is configured (e.g. if dim and color is unconfigured, then only allow on/off). Not sure if this lies with Logitech, if so I'm ok, but if there are different HUE types that could be used then I would prefer ha-bridge to refer accordingly.
One observation. -Would it be possible to move the Harmony activities/devices out of the Lights list?? I don't see the point in my Harmony picking up these. (under lights I get a mix of lights and harmony items, on my harmony..) -How about moving them out to a separate section of the REST API? For instance /api/noname/harmony/
ok - startet with Fhem device check. - first at all i deleted all old device created with RC3
@spiff972 So for the API that is exposed to the harmony it is the HUE Api. So for the lights, we return one type which is a color light so that in any instance all the control is available. I know that is not what you are looking for and we may change that so you can select the type of Hue Device. For your second issue, the Hue Api returns all of it's configured lights. If you do not need to control anything on the Harmony from ha-bridge, do not configure that in bridge control. If you do, there is no good way to not show those devices to any specific client.
@essera Thakns for the info. As for the command that is sent to the Fhem, that URL decription that the ha-bridge uses is converted into the command line you show. You will need to turn debugging on for the ha-bridge plugin for fhem to see what is sent.
@bwssytems I do need to control things on the Harmony hub. -I want to use ha-bridge as the "middle man" between LogicMachine (LM) /KNX and Harmony hub, and I need 2-way communication. -For instance, I want to add "watch TV", "PS4 gaming" and "PowerOff" to the bridge, so that I can incorporate that in my LM visualization. -Or even add "PowerOff" to my "Leaving home" and "Goodnight" scenes (activated by KNX pushbuttons, which trigger a script to talk to ha-bridge).
I see that you have a restful-harmony that could do the job, but it would be better to have it combined into ha-bridge. -I understand this is not the version to include it into, but if I could create harmony devices/activities as "harmony" instead of "lights", or at least have a filter to restrict what the hub can see, it would be great.
@spiff972 Your request would be an enhancement request. Please add another issue here requesting that enhancement as it would require a switch to use this as most people want that to be part of the normal return of lights in the hue api.
@spiff972 In case you don't have too many devices accessing the HA-Bridge lights you could simply use the already implemented "Filter Address" option for your lights.
If this setting is empty for an individual light every device can see the light. If you put one or more IP addresses in the filter then only the named IP-adresses can see the light.
With this setting it is easy to enable a light for a (small) number of devices. I don't have a really big network so I did exclude a single device by naming all other devices which access the lights.
@bwssytems maybe it would be nice if there would be an alternative to toggle this option the other way around, e.g. "show this light to everything except for the named IP" or to simply have both.
@FloFoer That would be something to consider and Thanks for suggesting this. Much simpler solution.
@essera I have found an issue with the command building for FHEM. Will be fixed in the next build.
@bwssytems
So for the lights, we return one type which is a color light so that in any instance all the control is available.
This is actually not entirely true. In case of real Hue passthru bulbs we return the real light type, this means "Extended color light", "Dimmable light" or "On/Off plug-in unit".
If its not a passthrough device we return color light only if the colorUrl is not empty, if it is empty, we return dimmable light.
@FloFoer Forgot about that code. I was thinking we should probably add the selection for the hue device type as that would help people organize what the capabilities are.
I thought about doing it that way too the moment I did this during color implementation, but I prefered to let it decide this automatically, because it doesn't really make sense to present it as a color light if it doesn't do anything if it would receive a color command.
Also i discourage the use of the "On/Off plug-in unit" light type - at least for alexa users - because alexa does discover this type only via the online skill, just like groups, apparently.
That would be less confusion. Also, I updated the call code in HueMulator to just ignore a call if the URL is null or empty.
Thanks guys, I've added the filter on the harmony items. They are now only visible for LM and my desktop computer. -Sure it would be great to have a reverse-filter, just blocking the hub.
I also created a new light item from scratch and only adding the on/off items. Now the remote only shows light and dimmer. -So the code is working, except it would be great to have the dimmer part excluded as well. -No problem short term for me as I will mainly trigger dimmable lights.
For my color issue, this can be reproduced by putting something into the color item, and then try to delete that item. -It goes away, BUT after "save" it comes back with [] (square brackets) as target item, all other fields are blank.

Regarding this
Is there already a way, to bypass the ~5 second timeout on alexa?
One possibility would be to create the option to define a light as "async execution" which would result in it getting executed in a separate thread and to return a "success" message immediately. (Of course errors would get lost that way)
_(In my own setup i have a queue where the processing of each call gets added and executed sequentially in a seperate thread. So i decided to execute everything async, because i don't want to wait so long for alexas "OK" and because my cinema sequences are all longer than 5 seconds and Alexa is even so stupid as to try it a second time after the first timeout. This retry resulted in mayor annoyances with my cinema sequences..)_
The issue with harmony still expecting a dimmable item to keep the status is still there. -See my first comment about dummy MQTT dimmable item.
Ok all, lets try the new version 5.2.0RC5
I tried the creation and ON/off of an Fhem device.
Debug log is coming per Mail.
@essera So it calls the FHEM and gets a successful response. This is the command from the log http://192.168.178.26:8083/fhem?cmd=set%20steckdose3%20/on
Edit: Well ignore that last item. It looks as if I am putting a slash before the command it should be just the space.
@spiff972 I updated the initialization after a remove item. Should be good in the next release.
@FloFoer I still think adding the device type selection into the edit would help with others using different systems. It seems the Harmony detects this and builds its screen accordingly. The default would be the way it works now and you would have to set it in the edit screen to change it.
New RC6 jar available at link above!
Fixed minor FHEM, OpenHAB and added Broadlink support
Can you explain how the broadlink is supported?
How do I setup to send a command?
Link for RC6 returns 404. Please fix.
The source code is missing.
link fixed
Page not found.
@bwssytems I use a separate "ha-bridge" user @ Fibaro HomeCenter to select which scenes and devices will be seen by ha-bridge. I don't want to offer my doorlock to Alexa.
And with RC6 I'm still getting the same "undefined with status: Server Error - 500" error.
But as I can see in the log: the bridge can see my scenes correctly.
Hopefully my log is better for you this time :)
01-20-2018 16:47:49.143 | DEBUG | consolidating scenes for fibaros | com.bwssystems.HABridge.plugins.fibaro.FibaroHome
-- | -- | -- | --
01-20-2018 16:47:49.133 | DEBUG | consolidating devices for fibaros | com.bwssystems.HABridge.plugins.fibaro.FibaroHome
01-20-2018 16:47:49.182 | DEBUG | getRooms response: <<<[{"id":4,"name":"Dachboden","sectionID":4,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":1},{"id":5,"name":"Badezimmer","sectionID":5,"icon":"room_wanna","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":2},{"id":6,"name":"B眉ro","sectionID":5,"icon":"room_office","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":3},{"id":7,"name":"Flur","sectionID":5,"icon":"room_schody2","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":4},{"id":8,"name":"Schlafzimmer","sectionID":5,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":5},{"id":9,"name":"Emelie","sectionID":5,"icon":"room_wardrobe","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":6},{"id":10,"name":"Mino","sectionID":5,"icon":"room_garage","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":7},{"id":11,"name":"Flur","sectionID":6,"icon":"room_drzwiwejsciowe","defaultSensors":{"temperature":181,"humidity":0,"light":182},"defaultThermostat":0,"sortOrder":8},{"id":12,"name":"Wohnzimmer","sectionID":6,"icon":"room_sofa","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":9},{"id":13,"name":"K眉che","sectionID":6,"icon":"room_dining","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":10},{"id":14,"name":"Esszimmer","sectionID":6,"icon":"room_jadalnia","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":11},{"id":15,"name":"HWR","sectionID":6,"icon":"room_laundry","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":12},{"id":16,"name":"G盲ste WC","sectionID":6,"icon":"room_toilet","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":13},{"id":17,"name":"Haus","sectionID":7,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":14},{"id":18,"name":"Terrasse","sectionID":7,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":15}] >>> | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
01-20-2018 16:47:49.183 | DEBUG | getRooms response: <<<[{"id":4,"name":"Dachboden","sectionID":4,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":1},{"id":5,"name":"Badezimmer","sectionID":5,"icon":"room_wanna","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":2},{"id":6,"name":"B眉ro","sectionID":5,"icon":"room_office","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":3},{"id":7,"name":"Flur","sectionID":5,"icon":"room_schody2","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":4},{"id":8,"name":"Schlafzimmer","sectionID":5,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":5},{"id":9,"name":"Emelie","sectionID":5,"icon":"room_wardrobe","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":6},{"id":10,"name":"Mino","sectionID":5,"icon":"room_garage","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":7},{"id":11,"name":"Flur","sectionID":6,"icon":"room_drzwiwejsciowe","defaultSensors":{"temperature":181,"humidity":0,"light":182},"defaultThermostat":0,"sortOrder":8},{"id":12,"name":"Wohnzimmer","sectionID":6,"icon":"room_sofa","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":9},{"id":13,"name":"K眉che","sectionID":6,"icon":"room_dining","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":10},{"id":14,"name":"Esszimmer","sectionID":6,"icon":"room_jadalnia","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":11},{"id":15,"name":"HWR","sectionID":6,"icon":"room_laundry","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":12},{"id":16,"name":"G盲ste WC","sectionID":6,"icon":"room_toilet","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":13},{"id":17,"name":"Haus","sectionID":7,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":14},{"id":18,"name":"Terrasse","sectionID":7,"icon":"room_bedroom","defaultSensors":{"temperature":0,"humidity":0,"light":0},"defaultThermostat":0,"sortOrder":15}] >>> | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
01-20-2018 16:47:49.220 | INFO | Found: 15 rooms | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
01-20-2018 16:47:49.246 | DEBUG | getScenes response: <<<[{"id":33,"name":"Fernsehlicht aus","type":"com.fibaro.luaScene","roomID":12,"iconID":6,"runConfig":"MANUAL_ONLY","alexaProhibited":true,"autostart":true,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":4,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":true,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":17},{"id":68,"name":"Rollos runterfahren","type":"com.fibaro.luaScene","roomID":14,"iconID":6,"runConfig":"MANUAL_ONLY","alexaProhibited":true,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":true,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":8},{"id":69,"name":"Rollos hochfahren","type":"com.fibaro.luaScene","roomID":14,"iconID":6,"runConfig":"MANUAL_ONLY","alexaProhibited":true,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":true,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":9},{"id":93,"name":"Alles aus Haus","type":"com.fibaro.magicScene","roomID":12,"runConfig":"TRIGGER_AND_MANUAL","alexaProhibited":false,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":false,"properties":"{\"conditionDeviceId\":163,\"conditionObjectType\":\"device\",\"conditionId\":\"condition_centralSceneEvent1HeldDown_163\",\"conditionLua\":\"true\",\"conditionValue\":\"\",\"conditionRooms\":[],\"trigger\":\"163 CentralSceneEvent 1 HeldDown\n163 CentralSceneEvent 1 Released\",\"actionDeviceId\":92,\"actionObjectType\":\"scene\",\"actionId\":\"action_runScene_92\",\"actionLua\":\"fibaro:startScene(92);\",\"actionValue\":\"\",\"actionRooms\":[],\"looping\":false}","triggers":{"properties":[],"globals":[],"events":[{"deviceId":163,"eventName":"CentralSceneEvent","args":["1","Released"]}],"weather":[]},"actions":{"devices":[],"scenes":[92],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":18},{"id":94,"name":"Fernsehlicht an","type":"com.fibaro.blockScene","roomID":12,"iconID":5,"runConfig":"MANUAL_ONLY","alexaProhibited":false,"autostart":true,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":false,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[112,114],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":10},{"id":95,"name":"Fernsehlicht + aus","type":"com.fibaro.blockScene","roomID":12,"iconID":5,"runConfig":"MANUAL_ONLY","alexaProhibited":false,"autostart":true,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":false,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[7,10,11,12,13,14,57,60,74,77,97,112,114],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":19},{"id":96,"name":"Fernsehlicht Wohnzim","type":"com.fibaro.magicScene","roomID":12,"runConfig":"TRIGGER_AND_MANUAL","alexaProhibited":false,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":false,"properties":"{\"conditionDeviceId\":163,\"conditionObjectType\":\"device\",\"conditionId\":\"condition_centralSceneEvent2Pressed_163\",\"conditionLua\":\"true\",\"conditionValue\":\"\",\"conditionRooms\":[],\"trigger\":\"163 CentralSceneEvent 2 Pressed\",\"actionDeviceId\":94,\"actionObjectType\":\"scene\",\"actionId\":\"action_runScene_94\",\"actionLua\":\"fibaro:startScene(94);\",\"actionValue\":\"\",\"actionRooms\":[],\"looping\":false}","triggers":{"properties":[],"globals":[],"events":[{"deviceId":163,"eventName":"CentralSceneEvent","args":["2","Pressed"]}],"weather":[]},"actions":{"devices":[],"scenes":[94],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":20},{"id":98,"name":"Fersehlicht blau","type":"com.fibaro.blockScene","roomID":12,"iconID":5,"runConfig":"MANUAL_ONLY","alexaProhibited":false,"autostart":true,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":false,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[112,114],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":11},{"id":99,"name":"Fernsehlicht blau+au","type":"com.fibaro.blockScene","roomID":12,"iconID":5,"runConfig":"MANUAL_ONLY","alexaProhibited":false,"autostart":true,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":true,"isLua":false,"properties":"","triggers":{"properties":[],"globals":[],"events":[],"weather":[]},"actions":{"devices":[7,10,11,12,13,14,112,114],"scenes":[],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":21},{"id":100,"name":"Fernsehlicht blau+au","type":"com.fibaro.magicScene","roomID":12,"runConfig":"TRIGGER_AND_MANUAL","alexaProhibited":false,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":false,"properties":"{\"conditionDeviceId\":163,\"conditionObjectType\":\"device\",\"conditionId\":\"condition_centralSceneEvent3HeldDown_163\",\"conditionLua\":\"true\",\"conditionValue\":\"\",\"conditionRooms\":[],\"trigger\":\"163 CentralSceneEvent 3 HeldDown\n163 CentralSceneEvent 3 Released\",\"actionDeviceId\":99,\"actionObjectType\":\"scene\",\"actionId\":\"action_runScene_99\",\"actionLua\":\"fibaro:startScene(99);\",\"actionValue\":\"\",\"actionRooms\":[],\"looping\":false}","triggers":{"properties":[],"globals":[],"events":[{"deviceId":163,"eventName":"CentralSceneEvent","args":["3","Released"]}],"weather":[]},"actions":{"devices":[],"scenes":[99],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":22},{"id":101,"name":"Fersehlicht blau Woh","type":"com.fibaro.magicScene","roomID":12,"runConfig":"TRIGGER_AND_MANUAL","alexaProhibited":false,"autostart":false,"protectedByPIN":false,"killable":true,"killOtherInstances":false,"maxRunningInstances":2,"runningInstances":0,"instances":[],"runningManualInstances":0,"visible":false,"properties":"{\"conditionDeviceId\":163,\"conditionObjectType\":\"device\",\"conditionId\":\"condition_centralSceneEvent3Pressed_163\",\"conditionLua\":\"true\",\"conditionValue\":\"\",\"conditionRooms\":[],\"trigger\":\"163 CentralSceneEvent 3 Pressed\",\"actionDeviceId\":98,\"actionObjectType\":\"scene\",\"actionId\":\"action_runScene_98\",\"actionLua\":\"fibaro:startScene(98);\",\"actionValue\":\"\",\"actionRooms\":[],\"looping\":false}","triggers":{"properties":[],"globals":[],"events":[{"deviceId":163,"eventName":"CentralSceneEvent","args":["3","Pressed"]}],"weather":[]},"actions":{"devices":[],"scenes":[98],"groups":[]},"liliStartCommand":"","liliStopCommand":"","sortOrder":23}] >>> | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
01-20-2018 16:47:49.259 | DEBUG | getDevices response: <<<[{"id":7,"name":"Deckenspots","roomID":12,"type":"com.fibaro.FGD212","baseType":"com.fibaro.multilevelSwitch","enabled":true,"visible":true,"isPlugin":false,"parentId":5,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","levelChange","light","power","zwave","zwaveAlarm","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,4,5","zwaveVersion":"3.5","RFProtectionState":"0","RFProtectionSupport":"3","alarmLevel":"0","alarmType":"0","configured":true,"dead":"false","deviceControlType":"23","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"15","emailNotificationID":"0","emailNotificationType":"0","endPointId":"1","energy":"7.20","isLight":"true","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"2","parametersTemplate":"796","power":"0.00","productInfo":"1,15,1,2,16,0,3,5","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"25","serialNumber":"h'000000000001c1b4","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"0"},"actions":{"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"startLevelDecrease":0,"startLevelIncrease":0,"stopLevelChange":0,"turnOff":0,"turnOn":0},"created":1516410078,"modified":1516410078,"sortOrder":1},{"id":13,"name":"Licht K眉che","roomID":13,"type":"com.fibaro.binarySwitch","baseType":"com.fibaro.actor","enabled":true,"visible":true,"isPlugin":false,"parentId":10,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","light","power","zwave","zwaveMultiChannelAssociation","zwaveProtection"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,4,5","zwaveVersion":"3.2","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"2","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"2","emailNotificationID":"0","emailNotificationType":"0","endPointId":"1","energy":"9.39","isLight":"true","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"3","parametersTemplate":"781","power":"0.00","productInfo":"1,15,2,3,16,0,3,2","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","serialNumber":"h'000000000000450d","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"false"},"actions":{"reconfigure":0,"reset":0,"turnOff":0,"turnOn":0},"created":1516410079,"modified":1516410079,"sortOrder":2},{"id":14,"name":"Lampe Tisch","roomID":14,"type":"com.fibaro.binarySwitch","baseType":"com.fibaro.actor","enabled":true,"visible":true,"isPlugin":false,"parentId":10,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","light","power","zwave","zwaveMultiChannelAssociation","zwaveProtection"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,4,5","zwaveVersion":"3.2","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"2","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"2","emailNotificationID":"0","emailNotificationType":"0","endPointId":"2","energy":"29.06","isLight":"true","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"3","parametersTemplate":"781","power":"0.00","productInfo":"1,15,2,3,16,0,3,2","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","serialNumber":"h'000000000000450d","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"false"},"actions":{"reconfigure":0,"reset":0,"turnOff":0,"turnOn":0},"created":1516410079,"modified":1516410079,"sortOrder":3},{"id":18,"name":"Licht","roomID":16,"type":"com.fibaro.binarySwitch","baseType":"com.fibaro.actor","enabled":true,"visible":true,"isPlugin":false,"parentId":15,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","light","power","zwave","zwaveMultiChannelAssociation","zwaveProtection"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,4,5","zwaveVersion":"3.2","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"2","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"2","emailNotificationID":"0","emailNotificationType":"0","endPointId":"1","energy":"2.70","isLight":"true","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"4","parametersTemplate":"781","power":"0.00","productInfo":"1,15,2,3,16,0,3,2","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","serialNumber":"h'00000000000044b4","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"false"},"actions":{"reconfigure":0,"reset":0,"turnOff":0,"turnOn":0},"created":1516410079,"modified":1516410079,"sortOrder":4},{"id":26,"name":"Licht","roomID":5,"type":"com.fibaro.FGD212","baseType":"com.fibaro.multilevelSwitch","enabled":true,"visible":true,"isPlugin":false,"parentId":24,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","levelChange","light","power","zwave","zwaveAlarm","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,4,5","zwaveVersion":"3.5","RFProtectionState":"0","RFProtectionSupport":"3","alarmLevel":"0","alarmType":"0","configured":true,"dead":"false","deviceControlType":"23","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"15","emailNotificationID":"0","emailNotificationType":"0","endPointId":"1","energy":"27.95","isLight":"true","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"7","parametersTemplate":"796","power":"0.00","productInfo":"1,15,1,2,16,0,3,5","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"0","serialNumber":"h'0000000000001fec","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"0"},"actions":{"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"startLevelDecrease":0,"startLevelIncrease":0,"stopLevelChange":0,"turnOff":0,"turnOn":0},"created":1516410079,"modified":1516410079,"sortOrder":5},{"id":57,"name":"Fenster rechts","roomID":14,"type":"com.fibaro.FGRM222","baseType":"com.fibaro.FGR","enabled":true,"visible":true,"isPlugin":false,"parentId":56,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["energy","levelChange","power","zwave","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"25.25","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"54","deviceIcon":"87","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"0.77","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"19","parametersTemplate":"721","power":"0.00","productInfo":"1,15,3,2,16,0,25,25","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionLocal":"0","protectionLocalSupport":"5","protectionRF":"0","protectionRFSupport":"3","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"0","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"99","value2":"0"},"actions":{"close":0,"open":0,"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"setValue2":1,"startLevelDecrease":0,"startLevelIncrease":0,"stop":0,"stopLevelChange":0},"created":1516410080,"modified":1516410080,"sortOrder":12},{"id":60,"name":"Fenster mitte","roomID":14,"type":"com.fibaro.FGRM222","baseType":"com.fibaro.FGR","enabled":true,"visible":true,"isPlugin":false,"parentId":59,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["energy","levelChange","power","zwave","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"25.25","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"54","deviceIcon":"87","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"0.70","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"20","parametersTemplate":"721","power":"0.00","productInfo":"1,15,3,2,16,0,25,25","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionLocal":"0","protectionLocalSupport":"5","protectionRF":"0","protectionRFSupport":"3","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"16","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"99","value2":"0"},"actions":{"close":0,"open":0,"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"setValue2":1,"startLevelDecrease":0,"startLevelIncrease":0,"stop":0,"stopLevelChange":0},"created":1516410080,"modified":1516410080,"sortOrder":13},{"id":74,"name":"Fenster links","roomID":14,"type":"com.fibaro.FGRM222","baseType":"com.fibaro.FGR","enabled":true,"visible":true,"isPlugin":false,"parentId":73,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["energy","levelChange","power","zwave","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"25.25","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"54","deviceIcon":"87","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"0.63","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"21","parametersTemplate":"721","power":"0.00","productInfo":"1,15,3,2,16,0,25,25","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionLocal":"0","protectionLocalSupport":"5","protectionRF":"0","protectionRFSupport":"3","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"0","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"99","value2":"0"},"actions":{"close":0,"open":0,"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"setValue2":1,"startLevelDecrease":0,"startLevelIncrease":0,"stop":0,"stopLevelChange":0},"created":1516410080,"modified":1516410080,"sortOrder":14},{"id":77,"name":"Fenster Sideboard","roomID":14,"type":"com.fibaro.FGRM222","baseType":"com.fibaro.FGR","enabled":true,"visible":true,"isPlugin":false,"parentId":76,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["energy","levelChange","power","zwave","zwaveMultiChannelAssociation","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"25.25","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"54","deviceIcon":"87","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"0.62","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"22","parametersTemplate":"721","power":"0.00","productInfo":"1,15,3,2,16,0,25,25","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionLocal":"0","protectionLocalSupport":"5","protectionRF":"0","protectionRFSupport":"3","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"0","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","useTemplate":"true","userDescription":"","value":"98","value2":"0"},"actions":{"close":0,"open":0,"reconfigure":0,"reset":0,"sceneActivationSet":0,"setValue":1,"setValue2":1,"startLevelDecrease":0,"startLevelIncrease":0,"stop":0,"stopLevelChange":0},"created":1516410081,"modified":1516410081,"sortOrder":15},{"id":112,"name":"Stehlampe","roomID":12,"type":"com.fibaro.multilevelSwitch","baseType":"com.fibaro.binarySwitch","enabled":true,"visible":true,"isPlugin":false,"parentId":111,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","fibaroFirmwareUpdate","levelChange","light","zwave","zwaveSwitchAll"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Domitech Products","zwaveInfo":"3,4,5","zwaveVersion":"5.14","configured":true,"dead":"false","deviceControlType":"23","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"15","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","firmwareUpdate":"{\"info\":\"\",\"progress\":0,\"status\":\"UpToDate\",\"updateVersion\":\"5.14\"}","isLight":"true","liliOffCommand":"","liliOnCommand":"","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"27","parametersTemplate":"807","productInfo":"2,14,76,66,49,52,5,14","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","serialNumber":"","smsNotificationID":"0","smsNotificationType":"0","switchAllMode":"SWITCH_ALL_INCLUDED_IN_THE_ALL_ON_ALL_OFF_FUNCTIONALITY","updateVersion":"","useTemplate":"true","userDescription":"","value":"0"},"actions":{"abortUpdate":1,"reconfigure":0,"retryUpdate":1,"setValue":1,"startLevelDecrease":0,"startLevelIncrease":0,"startUpdate":1,"stopLevelChange":0,"turnOff":0,"turnOn":0,"updateFirmware":1},"created":1516410082,"modified":1516410082,"sortOrder":6},{"id":114,"name":"RGBW","roomID":12,"type":"com.fibaro.FGRGBW441M","baseType":"com.fibaro.colorController","enabled":true,"visible":true,"isPlugin":false,"parentId":113,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["deviceGrouping","energy","fibaroFirmwareUpdate","levelChange","light","power","zwave","zwaveSwitchAll"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"26.25","associationMode":"0","brightness":"0","buttonType":"0","color":"0,0,0,0","configured":true,"currentProgram":"0","currentProgramID":"0","dead":"false","deviceControlType":"50","deviceGroup":"[]","deviceGroupMaster":"0","deviceIcon":"15","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"1.70","favoriteProgram":"0","firmwareUpdate":"{\"info\":\"\",\"progress\":0,\"status\":\"UpToDate\",\"updateVersion\":\"26.25\"}","isLight":"true","lastColorSet":"0,5,23,0","liliOffCommand":"","liliOnCommand":"","log":"","logTemp":"","manufacturer":"","markAsDead":"true","mode":"0","model":"","nodeId":"28","parametersTemplate":"231","power":"0.00","productInfo":"1,15,9,0,16,0,26,25","programsSortOrder":"1,2,3,4,5","pushNotificationID":"0","pushNotificationType":"0","rememberColor":"false","remoteGatewayId":"0","saveLogs":"true","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","switchAllMode":"SWITCH_ALL_INCLUDED_IN_THE_ALL_ON_ALL_OFF_FUNCTIONALITY","updateVersion":"","useTemplate":"true","userDescription":"","value":"0"},"actions":{"abortUpdate":1,"reconfigure":0,"reset":0,"retryUpdate":1,"setB":1,"setBrightness":1,"setColor":1,"setFavoriteProgram":2,"setG":1,"setR":1,"setValue":1,"setW":1,"startLevelDecrease":0,"startLevelIncrease":0,"startProgram":1,"startUpdate":1,"stopLevelChange":0,"turnOff":0,"turnOn":0,"updateFirmware":1},"created":1516410082,"modified":1516410082,"sortOrder":7},{"id":122,"name":"Fenster","roomID":12,"type":"com.fibaro.FGRM222","baseType":"com.fibaro.FGR","enabled":true,"visible":true,"isPlugin":false,"parentId":121,"remoteGatewayId":0,"viewXml":false,"configXml":false,"interfaces":["energy","fibaroFirmwareUpdate","levelChange","power","zwave","zwaveConfiguration","zwaveProtection","zwaveSceneActivation"],"properties":{"pollingTimeSec":0,"zwaveCompany":"Fibargroup","zwaveInfo":"3,3,52","zwaveVersion":"24.24","RFProtectionState":"0","RFProtectionSupport":"3","configured":true,"dead":"false","deviceControlType":"54","deviceIcon":"87","emailNotificationID":"0","emailNotificationType":"0","endPointId":"0","energy":"0.16","firmwareUpdate":"{\"info\":\"\",\"progress\":0,\"status\":\"UpToDate\",\"updateVersion\":\"24.24\"}","liliOffCommand":"","liliOnCommand":"","localProtectionState":"0","localProtectionSupport":"5","log":"","logTemp":"","manufacturer":"","markAsDead":"true","model":"","nodeId":"29","parametersTemplate":"249","power":"0.00","productInfo":"1,15,3,1,16,1,24,24","protectionExclusiveControl":"0","protectionExclusiveControlSupport":"false","protectionState":"0","protectionTimeout":"0","protectionTimeoutSupport":"false","pushNotificationID":"0","pushNotificationType":"0","remoteGatewayId":"0","saveLogs":"true","sceneActivation":"0","serialNumber":"","showEnergy":"true","smsNotificationID":"0","smsNotificationType":"0","updateVersion":"","useTemplate":"true","userDescription":"","value":"0","value2":"0"},"actions":{"abortUpdate":1,"close":0,"getParameter":1,"open":0,"reconfigure":0,"reset":0,"retryUpdate":1,"sceneActivationSet":0,"setParameter":2,"setValue":1,"setValue2":1,"startLevelDecrease":0,"startLevelIncrease":0,"startUpdate":1,"stop":0,"stopLevelChange":0,"updateFirmware":1},"created":1516410083,"modified":1516410083,"sortOrder":16}] >>> | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
01-20-2018 16:47:49.291 | ERROR | 聽 | spark.http.matching.GeneralError
01-20-2018 16:47:49.384 | INFO | Found: 12 devices | com.bwssystems.HABridge.plugins.fibaro.FibaroInfo
On RC6, harmony behavior seems to be working perfectly now, for my purposes. Can't speak for anyone else's situation though. Thanks for working on this!
Is OpenHAB require for the Broadlink RM?
Please tell me that's not required for HA-Bridge to support the Broadlink RM!
never mind think I found it. don't know how I missed it.
I'm stumped :(
Does the HA-Bridge learn IR and or RF ?
Do we need to manually put in the codes? This isn't a big deal as I have them, I'm just not sure exactly what to put in the actions boxes. What is expected is the mac id required too?
@bwssytems
OK - I麓ve test the Switching of Fhem device one again
Debug Log of switching the device off:
01-20-2018 23:22:54.356 | DEBUG | Stats: [leased: 0; pending: 0; available: 0; max: 200] | com.bwssystems.HABridge.plugins.http.HttpClientPool
-- | -- | -- | --
01-20-2018 23:22:59.359 | DEBUG | Stats: [leased: 0; pending: 0; available: 0; max: 200] | com.bwssystems.HABridge.plugins.http.HttpClientPool
01-20-2018 23:23:02.848 | DEBUG | HueMulator PUT called on api/* with request <<>>, and body <<<{"on": false}>>> | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:02.850 | DEBUG | matchedPart: :userid = a9TykGSkLQX7qlH7BEUEHQCiALsTLa5jHkZl6qBA | spark.Request
01-20-2018 23:23:02.851 | DEBUG | matchedPart: :id = 17 | spark.Request
01-20-2018 23:23:02.852 | DEBUG | hue state change requested: a9TykGSkLQX7qlH7BEUEHQCiALsTLa5jHkZl6qBA from 192.168.178.35 body: {"on": false} | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:02.852 | DEBUG | validateWhitelistUser: found a user
01-20-2018 23:23:02.853 | DEBUG | Calling on-off as requested. | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:02.854 | DEBUG | Decode Json for url items: [{"item":"{\"url\":\"http://192.168.178.26:8083/fhem?cmd=set%20ess_dimmer_Dim%20\",\"command\":\"off\"}","type":"fhemDevice"}] | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:02.855 | DEBUG | Calling Home device handler for type : fhemDevice | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:02.859 | DEBUG | Request <
01-20-2018 23:23:02.861 | DEBUG | Request <
01-20-2018 23:23:02.862 | DEBUG | calling FHEM: http://192.168.178.26:8083/fhem?cmd=set%20ess_dimmer_Dim%20off | com.bwssystems.HABridge.plugins.fhem.FHEMInstance
01-20-2018 23:23:02.862 | DEBUG | doHttpRequest with url: http://192.168.178.26:8083/fhem?cmd=set%20ess_dimmer_Dim%20off with http command: POST with body: null | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-20-2018 23:23:02.863 | DEBUG | Making outbound call in doHttpRequest: <<
01-20-2018 23:23:02.918 | DEBUG | POST execute (0) on URL responded: 302 | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-20-2018 23:23:02.928 | WARN | HTTP response code was not an expected successful response of between 200 - 299, the code was: HTTP/1.1 302 Found with the content of <<<>>> | com.bwssystems.HABridge.plugins.http.HTTPHandler
01-20-2018 23:23:02.929 | DEBUG | doHttpRequest returned data: <> | com.bwssystems.HABridge.plugins.fhem.FHEMInstance
01-20-2018 23:23:03.030 | DEBUG | HueMulator GET called on api/* with request <<>>, and body <<<>>> | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:03.031 | DEBUG | matchedPart: :userid = a9TykGSkLQX7qlH7BEUEHQCiALsTLa5jHkZl6qBA | spark.Request
01-20-2018 23:23:03.032 | DEBUG | matchedPart: :id = 17 | spark.Request
01-20-2018 23:23:03.033 | DEBUG | hue light requested: 17 for user: a9TykGSkLQX7qlH7BEUEHQCiALsTLa5jHkZl6qBA from 192.168.178.35 | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:03.034 | DEBUG | validateWhitelistUser: found a user
01-20-2018 23:23:03.034 | DEBUG | found device named: Esszimmer Licht | com.bwssystems.HABridge.hue.HueMulator
01-20-2018 23:23:04.360 | DEBUG | Stats: [leased: 0; pending: 0; available: 1; max: 200] | com.bwssystems.HABridge.plugins.http.HttpClientPool
01-20-2018 23:23:07.874 | DEBUG | Get logmsgs. | com.bwssystems.HABridge.SystemControl
-> BTW - Great Job til now - i think the rest are Peanuts for you :-)
@essera A Question, when you tested the off command and you see th error above, did the FHEM actually turn the device off?
@essera For your question item 5, you would need to select the dim control type before you build. That drop down is above the list.
@tuicemen For the Broadlink support, there is a check box in the bridge control tab, select that and save. You will now see the broadlink tab and you then can see the list of devices. For IR handling, you will need the data string and convert it to hex byte representation and put it in the field in the broadlink devices tab and select build.
@bwssytems Is there something more I can do for you?
@xPRozAcx Could you post a link to a text log file from the command line for your system. That would be easier to use than the screen paste if you can.
@bwssytems you wanzend me to post the command line Logs instead of my screenshots.
I did like you wanted me to do 2 days ago.
Now you want me to put the log in a file and Share it for Download?
Am I right?
@xPRozAcx You posted the copy of the data from the web page, I would like you to get the log output of the java program that is either in your syslog on Linux or on a log file redirect when you start the ha-bridge.
Now I get you
@bwssytems Ok thanks, the converting I understand but the data string I have looks simular to this:
(shortened for readability)
{"data":"6d00b001000","mac":"34:be:34:c7:91:3c","name":"AllLightsOn"}
would I include the mac address and name?
what all do I convert to send?
would it just be "6d00b001000" with the quotes?
@bwssytems This is the log from /var/log/syslog
Jan 22 16:45:09 ha-bridge java[2588]: 2018-01-22 16:45:09,219 [qtp25999886-13] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroInfo - Found: 15 rooms
Jan 22 16:45:09 ha-bridge java[2588]: 2018-01-22 16:45:09,318 [qtp25999886-16] ERROR spark.http.matching.GeneralError -
Jan 22 16:45:09 ha-bridge java[2588]: com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected a string but was BEGIN_OBJECT at line 1 column 2436 path $[3].triggers.events[0]
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:220)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:40)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ArrayTypeAdapter.read(ArrayTypeAdapter.java:72)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.Gson.fromJson(Gson.java:879)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.Gson.fromJson(Gson.java:844)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.Gson.fromJson(Gson.java:793)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.Gson.fromJson(Gson.java:765)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.bwssystems.HABridge.plugins.fibaro.FibaroInfo.getScenes(FibaroInfo.java:154)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.bwssystems.HABridge.plugins.fibaro.FibaroHome.getScenes(FibaroHome.java:60)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.bwssystems.HABridge.plugins.fibaro.FibaroHome.getItems(FibaroHome.java:82)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.bwssystems.HABridge.devicemanagmeent.DeviceResource.lambda$setupEndpoints$11(DeviceResource.java:244)
Jan 22 16:45:09 ha-bridge java[2588]: #011at spark.ResponseTransformerRouteImpl$1.handle(ResponseTransformerRouteImpl.java:47)
Jan 22 16:45:09 ha-bridge java[2588]: #011at spark.http.matching.Routes.execute(Routes.java:61)
Jan 22 16:45:09 ha-bridge java[2588]: #011at spark.http.matching.MatcherFilter.doFilter(MatcherFilter.java:130)
Jan 22 16:45:09 ha-bridge java[2588]: #011at spark.embeddedserver.jetty.JettyHandler.doHandle(JettyHandler.java:50)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1568)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.Server.handle(Server.java:564)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:317)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:673)
Jan 22 16:45:09 ha-bridge java[2588]: #011at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:591)
Jan 22 16:45:09 ha-bridge java[2588]: #011at java.lang.Thread.run(Thread.java:745)
Jan 22 16:45:09 ha-bridge java[2588]: Caused by: java.lang.IllegalStateException: Expected a string but was BEGIN_OBJECT at line 1 column 2436 path $[3].triggers.events[0]
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.stream.JsonReader.nextString(JsonReader.java:831)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:422)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:410)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:40)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ArrayTypeAdapter.read(ArrayTypeAdapter.java:72)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:116)
Jan 22 16:45:09 ha-bridge java[2588]: #011at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:216)
Jan 22 16:45:09 ha-bridge java[2588]: #011... 28 common frames omitted
Jan 22 16:45:09 ha-bridge java[2588]: 2018-01-22 16:45:09,599 [qtp25999886-13] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroInfo - Found: 12 devices
Jan 22 16:45:11 ha-bridge java[2588]: 2018-01-22 16:45:11,463 [Thread-20] DEBUG com.bwssystems.HABridge.plugins.http.HttpClientPool - Stats: [leased: 0; pending: 0; available: 0; max: 200]
Jan 22 16:45:16 ha-bridge java[2588]: 2018-01-22 16:45:16,469 [Thread-20] DEBUG com.bwssystems.HABridge.plugins.http.HttpClientPool - Stats: [leased: 0; pending: 0; available: 0; max: 200]
@tuicemen It would be the string without the quotes inthe ir Command Field.
@xPRozAcx You don't have debug on and I need the snippet of the file itself. Edit the syslog file and save it to a different name. Upload that file to a bit bucket website with a link
Thanks I'll get to testing this.
plugins.fibaro and plugins.http debug turned on
Link to syslog file:
https://workupload.com/file/J7DtQAq
@bwssytems
The switch on/off works fine - i only got the error message in the log.
to Point 5: I dont know what do you mean with "you would need to select the dim control type before you build. That drop down is above the list"
I selected decimal percentage and pressed "Build Item" but in the device what was created, the dim option was still empty.
But i dont know if that ist what you mean.
@essera Will take a look at the dim population issue. As for the error message, it looks like the 302 return is valid for FHEM and will update.
@xPRozAcx That log shows the error that occurs on scene parsing. Will take a look as there is some issue occurring.
@essera What capabilities did your device show when you selected dim percentage? If it does not have dim as a capability, it will not create a dim command.
New RC7 jar to fix the FHEM and Fibaro reported issues.
RC7 fixed the "server error - 500" but scenes still do not show up @ fibaro scene list
Syslog Debug from RC7
https://workupload.com/file/Xm2s8vx
@xPRozAcx It seems the original author of the fibaro plugin decided that only scenes with a liliCommandStart should be used. I will update that to be any scene.
That would be nice.
Thank you
@bwssystems
The FHEM Device has no DIM Preferences but there is the PCT Setting. PCT from 1 to 100 to "dim" the Device. Mayby this ist the trigger to convert it into $(intensity.percent).
also: Little Issue - In FHEM Device List i have 4 Device. If i select the Checkboxes in the top of the row all 4 Device will select - Bulk add Shows (4) (Thats ok). If i select only one device all other device will be select too (thats wrong), but the Bulk Add shows correct (1)
@essera Thanks, I will add PCT to the list for dim handling. Also, that check box just allows the global control of the checkboxes and does not mean select all when you individually select, but it allows you to unselect all checked if you uncheck it. Works as designed.
New release candidate with some fixes for fhem and fibaro (@essera still doesn't fix the pct device)
reveiced my echo spot today and wasn't able to find all my devices as well.. updated to 5.2.0RC8 but still no luck. Any update on how to set things up? my log shows nothing.. habridge tested on port 80 and 8080... a also tried to turn my hue bridge off while alexa discovers devices
@mopby If that is 2nd gen it most likely will not find the ha-bridge. I am researching that as I just bought a 2nd gen echo to see what is going on.
@bwssytems its 2nd gen too I think. I helped myself with an echo dot that worked before. With both devices in my network running simultaneously I discover all devices. Thanks for your effort you put into this projekt
Alright, RC9 is out and it seems I need some feedback on the Broadlink integration.
Gives error when I try to use the built Broadlink device. Debug output attached. Thanks for your efforts!
habridge_log_emel_broadlink.txt
Installed RC9.
Now I get a lot of "device does not respond"-answers (My language is set to German, so I actually get: Das Ger盲t reagiert gerade nicht). The command is executed correctly, though.
I noticed that this seems to happen especially if voice commands have not been executed for a longer period (1 hour, maybe). If I say the same command right after "device does not respond", Alexa just tells me "OK". The next commands then also produce: "OK". So maybe this has to do with the new timing settings in HA Bridge?
Also, I still have serious discovery issues.
If I add a new device in HA Bridge and try to search for it, "Alexa, find new devices" never works. ("There are no new devices"). Erasing all devices on https://alexa.amazon.de and starting the discovery on the website, updates the list of devices on the Alexa-Website correctly sometimes, but not always. But Alexa never recognizes any of my voice commands then ("Device not found"). I then have to erase devices on the website over and over again and starting the discovery in the Alexa app either on my Fire 10 (2017) or the alexa app on my mobile phone. After several discoveries (sometimes more than 10), Voice commands finally start working again.
Because of this behaviour, I'm actually really scared of adding new devices to my HABridge ;-)
I have 1 Echo dot (bought May 2017), 1 Echo dot (bought November 2017), 1 Echo Show (bought November 2017), and 2 mid-size echos (not plus) (bought November 2017).
Concerning FHEM integration:
Wouldn't it be easier to enter just an URL like http://ipaddress:8050/webname instead of IP and port?
Actually it's not possible to connect to a non-standard-webname instance of FHEMweb.
Instead of the FHEM room textbox the list syntax (https://wiki.fhem.de/wiki/List) would make sense.
This would make filtering much easier.
will the Broadlink do RF as well as IR?
Sorry I haven't posted any feed back on this.
Had a PC melt down and got busy attempting to fix things.
I just noticed each time the router or Broadlink reboots the Broadlink gets assigned a new IP. So I either have to setup port forwarding or the HA-Bridge needs to resign the new IP to configured devices. I'll see about the port forward in the router and give another go. However I suspect since you can't tell the broadlink to use a static IP HA-Bridge may have to address using the mac address or something.
Ok noway to do a port forwarding it uses same ports as to many other things and I can't do it based on a mac address either.
Also when I convert to hex does it require a space every so many digits?
I get no errors on a send but nothing is turned on or off either. my hex string has no spaces.
@heikoh81 So, what type of commands are you running that the Alexa says does not respond. That is an indication of the command took too long. As far as your discovery issues, it will depend on which device is doing the discovery. You have some first gen devices that are working, but your Show will never discover and the later echos are gen 2 which is already known to have issues. Only discover on your gen 1 older devices.
@t0th0mas The room item is a request based on the original author of the FHEM integration. On the other item, we will have to add a webname I see for non-standard cases.
@tuicemen I'm not sure on how the RF items work in the api, will take a look. Also, there should not be any spaces in your hex data strings.
@ml9907 For your Broadlink issue, your log says the bind port, which is 40000, is in use by another app.
Broadlink feedback:
The sequences look likes this 26007800581c111b0f0f0e0f2b2b100e0f0f100d0f0f0e0f100d0f0f0e0f100e1d0f0e100e0f0e1e1f000add571c111b0f0f110c2c2b100e0e0f100e0e0f0e0f100e0e0f0e0f110d1d100e0f0e0f0e1e1f000add571d0f1c100e100d2c2c0e0f0e0f0f0f0e0f0e0f110d0f0e100d100f1c100e0f0f0e0f1e1d000d05 and 26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000
I redid some simple on/off commands Using the current Broadlinks address the old commands are still in the Bridge but with the wrong IP so naturaly they don't work.
Using the new commands I get a success send pop up but my devices never respond.
interestingly if I go to edit the device in the name field of the target item is "RM 2 Pro Plus"
{"id":"192.168.0.18-272a","name":"RM 2 Pro Plus","command":"ircommand","data":"2600.........
Should I be editing the name?
As well I did a complete port scan and my broadlink pro only uses ports 25,110,119,143,465,563,587,993 & 995 it doesn't see 40000 as being used (I scanned all the way to port 65535)
I managed to watch my broadlink send LED when sending the code from the HA-Bridge although the bridge states success the broadlink led never flickers to indicate it received a command
@tuicemen and @hphde It looks like I will need to look into the library I use for Broadlink.
Also, If you could turn on debug for the broadlink plugins that may help as well.
@bwssytems:
How can I control which echo does the search?
If I start the search by voice on my very first echo dot (May 2017), it still doesn't discover any devices on RC9 (or the previous ones).
I noticed that on starting the search with my Alexa app on the Fire HD 10, the LEDs of my two echos are flashing simultaneously.
I am most lucky in finding new devices on my mobile phone. There I had the Alexa app installed on buying my very first Echo dot (May 2017). It seems like the App is connected with either of my echos, although they are all in the same account. Maybe coincidence, though. No LED is flashing when doing the search on my mobile phone.
Is the "no discovery with Echo show" only a HABridge issue or a general issue?
Regarding the "no reaction":
I'm always issuing exactly one http-request. I'm calling a FHEM-URL that sets a dummy on or off.
This should always take the same amount of time. After that, FHEM handles what I want to do with my voice command.
@heikoh81
You can simply speak to the one you want to discover your devices and say, "Alexa, discover my devices".
@ml9907 Now that I took a look, it looks like the api I use does not close the port properly after discovery, so when you re-init it is still open. I will try to correct that internally if possible. Otherwise the other error you ha is that you are putting the chars '0x' in front of your hex string, Just remove the '0x'.
RC10 is up - turn on broadlink debug and restart as it will dump device info.
OK now the HA-Bridge doesn't even find a Broadlink
01-29-2018 21:29:29.176 | INFO | FHEM Home created. No FHEMs configured. | com.bwssystems.HABridge.plugins.fhem.FHEMHome
-- | -- | -- | --
01-29-2018 21:29:29.176 | INFO | Broadlink Home created. | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
01-29-2018 21:29:29.176 | INFO | Broadlink discover.... | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
01-29-2018 21:29:29.179 | WARN | Could not discover Broadlinks, with IO Exception | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
01-29-2018 21:29:29.181 | WARN | Error reading the file: data\group.db - Does not exist or is not readable. continuing... | com.bwssystems.HABridge.dao.GroupRepository
01-29-2018 21:29:29.181 | INFO | HABridge device management service started.... | com.bwssystems.HABridge.devicemanagmeent.DeviceResource
@tuicemen I need the command line log that has the exception data. Thanks
I forget how to pull that up HA-Bridge has been running so well.
I'm running it on windows.
Ok think I figure it out.
C:\Alex10>java -jar ha-bridge.jar
2018-01-30 13:05:32,088 [main] INFO com.bwssystems.HABridge.BridgeSettings - reading from config file: data/habridge.config
2018-01-30 13:05:32,463 [main] INFO com.bwssystems.HABridge.HABridge - HA Bridge (v5.2.0RC10) initializing....
2018-01-30 13:05:32,478 [main] INFO spark.staticfiles.StaticFilesConfiguration - StaticResourceHandler configured with folder = /public
2018-01-30 13:05:32,488 [main] INFO com.bwssystems.HABridge.SystemControl - System control service started....
2018-01-30 13:05:32.509:INFO::Thread-0: Logging initialized @665ms to org.eclipse.jetty.util.log.StdErrLog
2018-01-30 13:05:32,510 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - Initializing UDP response Socket...
2018-01-30 13:05:32,513 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - UDP response Seocket initialized to: 50000
2018-01-30 13:05:32,515 [main] INFO com.bwssystems.HABridge.plugins.http.HTTPHome - HTTP Home created.
2018-01-30 13:05:32,518 [main] INFO com.bwssystems.HABridge.plugins.harmony.HarmonyHome - Harmony Home created. No Harmony devices configured.
2018-01-30 13:05:32,519 [main] INFO com.bwssystems.HABridge.plugins.NestBridge.NestHome - Nest Home created. No Nest configured.
2018-01-30 13:05:32,519 [main] INFO com.bwssystems.HABridge.plugins.hue.HueHome - Hue passthru Home created. No Hue passtrhu systems configured.
2018-01-30 13:05:32,520 [main] INFO com.bwssystems.HABridge.plugins.hal.HalHome - HAL Home created. No HAL devices configured.
2018-01-30 13:05:32,521 [main] INFO com.bwssystems.HABridge.plugins.mqtt.MQTTHome - MQTT Home created. No MQTT Clients configured.
2018-01-30 13:05:32,522 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
2018-01-30 13:05:32,523 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
2018-01-30 13:05:32,528 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-01-30 13:05:32,528 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-01-30 13:05:32,528 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-01-30 13:05:32,528 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-01-30 13:05:32,528 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-01-30 13:05:32,533 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-01-30 13:05:32,533 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 13:05:32,543 [Thread-0] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - == Spark has ignited ...
2018-01-30 13:05:32,543 [Thread-0] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >> Listening on 0.0.0.0:80
2018-01-30 13:05:32.548:INFO:oejs.Server:Thread-0: jetty-9.4.z-SNAPSHOT
2018-01-30 13:05:32.568:INFO:oejs.session:Thread-0: DefaultSessionIdManager workerName=node0
2018-01-30 13:05:32.568:INFO:oejs.session:Thread-0: No SessionScavenger set, using defaults
2018-01-30 13:05:32.573:INFO:oejs.session:Thread-0: Scavenging every 660000ms
2018-01-30 13:05:32.653:INFO:oejs.AbstractConnector:Thread-0: Started ServerConnector@539fc{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-01-30 13:05:32.653:INFO:oejs.Server:Thread-0: Started @812ms
2018-01-30 13:05:37,545 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-01-30 13:05:37,545 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-01-30 13:05:37,560 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-01-30 13:05:37,570 [main] INFO com.bwssystems.HABridge.HABridge - Traceupnp: upnp config address: 192.168.0.100-useIface:false on web server: 0.0.0.0:80
2018-01-30 13:05:37,570 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-01-30 13:05:37,631 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-01-30 13:05:37,676 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: lo valid usable IP address: /127.0.0.1
2018-01-30 13:05:37,681 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding lo to our upnp join interface set.
2018-01-30 13:05:37,681 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: eth2 valid usable IP address: /192.168.0.100
2018-01-30 13:05:37,681 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding eth2 to our upnp join interface set.
2018-01-30 13:05:37,681 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
Funny now the bridge sees the Broadlink again.
@tuicemen Go into the bridge control tab and do a reinitialize, I have a hunch the code is hanging onto a socket.
Again I don't see any activity on the device (LED flickers when doing something)
2018-01-30 20:18:19,021 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.HueMulator - HueMulator PUT called on api/* with request
<<</api/f51cc6bb85024e12874492be0456f3c5/lights/27/state>>>, and body <<<{"on":true}>>>
2018-01-30 20:18:19,023 [qtp15713626-20] DEBUG spark.Request - matchedPart: :userid = f51cc6bb85024e12874492be0456f3c5
2018-01-30 20:18:19,024 [qtp15713626-20] DEBUG spark.Request - matchedPart: :id = 27
2018-01-30 20:18:19,024 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue state change requested: f51cc6bb85024e12
874492be0456f3c5 from 2001:16b8:2470:e300:1828:240c:42ed:24fd body: {"on":true}
2018-01-30 20:18:19,025 [qtp15713626-20] DEBUG com.bwssystems.HABridge.BridgeSecurity - validateWhitelistUser: found a user <f51cc6b
b85024e12874492be0456f3c5>
2018-01-30 20:18:19,025 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling on-off as requested.
2018-01-30 20:18:19,025 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.HueMulator - Decode Json for url items: [{"item":"{\"id\"
:\"192.168.112.129-2737\",\"name\":\"RM Mini\",\"command\":\"ircommand\",\"data\":\"26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e
0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e00
0ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000\"}","type":"broadlinkDevice"}]
2018-01-30 20:18:19,038 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling Home device handler for type : broad
linkDevice
2018-01-30 20:18:19,039 [qtp15713626-20] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request t
o send message to BroadlinkDevice: "{\"id\":\"192.168.112.129-2737\",\"name\":\"RM Mini\",\"command\":\"ircommand\",\"data\":\"26008
400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0
e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000
d0500000000\"}"
2018-01-30 20:18:19,041 [qtp15713626-20] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM
Mini
2018-01-30 20:18:19,041 [qtp15713626-20] DEBUG com.bwssystems.HABridge.hue.DeviceDataDecode - Request <<26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000>>, not done: false
2018-01-30 20:18:19,042 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Authentication method starts
2018-01-30 20:18:19,045 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Constructing AuthCmdPayload
2018-01-30 20:18:19,046 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Sending CmdPacket with AuthCmdPayload: cmd=65 len=80
2018-01-30 20:18:19,052 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-01-30 20:18:19,053 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for headers
2018-01-30 20:18:19,054 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-01-30 20:18:19,060 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-01-30 20:18:19,060 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-01-30 20:18:19,062 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-01-30 20:18:19,069 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-01-30 20:18:19,070 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Received datagram
2018-01-30 20:18:19,071 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Getting encrypted data from 0x38 to the end
2018-01-30 20:18:19,071 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - encDataLen=32
2018-01-30 20:18:19,073 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Creating AES instance with initial iv, key
2018-01-30 20:18:19,082 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Decrypting encrypted data
2018-01-30 20:18:19,090 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Decrypted. len=32
2018-01-30 20:18:19,091 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Packet received payload bytes: 6F0CBA90F6F5F6A2557E046B7D75490FEAB61CE8D9F80A88E7B88E58FD4E8AC6
2018-01-30 20:18:19,092 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Getting key from 0x04 to 0x14
2018-01-30 20:18:19,099 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Packet received key bytes: F6F5F6A2557E046B7D75490FEAB61CE8
2018-01-30 20:18:19,099 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Getting ID from 0x00 to 0x04
2018-01-30 20:18:19,100 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - Packet received id bytes: 6F0CBA90
2018-01-30 20:18:19,100 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - ID len=4
2018-01-30 20:18:19,109 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - End of authentication method
2018-01-30 20:18:19,110 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-01-30 20:18:19,113 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for headers
2018-01-30 20:18:19,114 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-01-30 20:18:19,124 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-01-30 20:18:19,125 [qtp15713626-20] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-01-30 20:18:19,132 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-01-30 20:18:19,134 [qtp15713626-20] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80

I see this btw when executing on/off the first time on the device but I don't see anything appropriate in the logs.
The faulty first request goes to /api//lights/28/state everything after goes to /api/f51cc6bb85024e12874492be0456f3c5/lights/28/state
@hphde that happens sometimes if the reply to get a testuser under neath does not complete before you hit the test button.
OK reinitialized
2018-01-30 15:55:56,466 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener - ended, restart found
2018-01-30 15:55:56,466 [main] INFO com.bwssystems.HABridge.HABridge - HA Bridge (v5.2.0RC10) reinitialization requessted....
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HABridge - Going to close all homes
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Manager close homes called....
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.tcp.TCPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.NestBridge.NestHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.NestBridge.NestHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.lifx.LifxHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.somfy.SomfyHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.openhab.OpenHABHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.fhem.FHEMHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.udp.UDPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.exec.CommandHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hass.HassHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.harmony.HarmonyHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hal.HalHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.udp.UDPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.hue.HueHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.harmony.HarmonyHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.tcp.TCPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.mqtt.MQTTHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.domoticz.DomoticzHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.exec.CommandHome
2018-01-30 15:55:56,471 [main] INFO com.bwssystems.HABridge.HomeManager - Closing home: com.bwssystems.HABridge.plugins.http.HTTPHome
2018-01-30 15:55:56,471 [Thread-38] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >>> Spark shutting down ...
2018-01-30 15:55:56.476:INFO:oejs.AbstractConnector:Thread-38: Stopped ServerConnector@16cedd0{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-01-30 15:55:56.476:INFO:oejs.session:Thread-38: Stopped scavenging
2018-01-30 15:55:56,476 [Thread-38] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - done
2018-01-30 15:56:01,473 [main] INFO com.bwssystems.HABridge.BridgeSettings - reading from config file: data/habridge.config
2018-01-30 15:56:01,508 [main] INFO com.bwssystems.HABridge.HABridge - HA Bridge (v5.2.0RC10) initializing....
2018-01-30 15:56:01,508 [main] INFO spark.staticfiles.StaticFilesConfiguration - StaticResourceHandler configured with folder = /public
2018-01-30 15:56:01,508 [main] INFO com.bwssystems.HABridge.SystemControl - System control service started....
2018-01-30 15:56:01,514 [Thread-40] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - == Spark has ignited ...
2018-01-30 15:56:01,514 [Thread-40] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >> Listening on 0.0.0.0:80
2018-01-30 15:56:01,514 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - Initializing UDP response Socket...
2018-01-30 15:56:01.514:INFO:oejs.Server:Thread-40: jetty-9.4.z-SNAPSHOT
2018-01-30 15:56:01,514 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - UDP response Seocket initialized to: 50000
2018-01-30 15:56:01,514 [main] INFO com.bwssystems.HABridge.plugins.http.HTTPHome - HTTP Home created.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.harmony.HarmonyHome - Harmony Home created. No Harmony devices configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.NestBridge.NestHome - Nest Home created. No Nest configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.hue.HueHome - Hue passthru Home created. No Hue passtrhu systems configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.hal.HalHome - HAL Home created. No HAL devices configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.mqtt.MQTTHome - MQTT Home created. No MQTT Clients configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
2018-01-30 15:56:01.515:INFO:oejs.session:Thread-40: DefaultSessionIdManager workerName=node0
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
2018-01-30 15:56:01.515:INFO:oejs.session:Thread-40: No SessionScavenger set, using defaults2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
2018-01-30 15:56:01.515:INFO:oejs.session:Thread-40: Scavenging every 600000ms
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-01-30 15:56:01,515 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-01-30 15:56:01,516 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-01-30 15:56:01,516 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-01-30 15:56:01,516 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 15:56:01,517 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, with IO Exception
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Unknown Source)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.DatagramSocket.<init>(Unknown Source)
at java.net.DatagramSocket.<init>(Unknown Source)
at com.github.mob41.blapi.BLDevice.discoverDevices(BLDevice.java:656)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.createHome(BroadlinkHome.java:71)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.<init>(BroadlinkHome.java:50)
at com.bwssystems.HABridge.HomeManager.buildHomes(HomeManager.java:128)
at com.bwssystems.HABridge.HABridge.main(HABridge.java:78)
2018-01-30 15:56:01.517:INFO:oejs.AbstractConnector:Thread-40: Started ServerConnector@190b2b3{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-01-30 15:56:01.517:INFO:oejs.Server:Thread-40: Started @468859ms
2018-01-30 15:56:01,519 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-01-30 15:56:01,519 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-01-30 15:56:01,520 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-01-30 15:56:01,520 [main] INFO com.bwssystems.HABridge.HABridge - Traceupnp: upnp config address: 192.168.0.100-useIface:false on web server: 0.0.0.0:80
2018-01-30 15:56:01,520 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-01-30 15:56:01,554 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-01-30 15:56:01,589 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: lo valid usable IP address: /127.0.0.1
2018-01-30 15:56:01,589 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding lo to our upnp join interface set.
2018-01-30 15:56:01,594 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: eth2 valid usable IP address: /192.168.0.100
2018-01-30 15:56:01,594 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding eth2 to our upnp join interface set.
2018-01-30 15:56:01,594 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-01-30 15:56:02,094 [qtp3295843-55] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: request of description.xml from: 127.0.0.1:80 filled in with address: 192.168.0.100:80
No Broadlinks found
@tuicemen That would be true and you got the bind exception. I'll put in some defensive code for that.
Increasingly i tried again got the same errors but broadlink shows 1 as being found. In the bridge.
However if i try to send a command to it i get error no broad link configured..
Ok new version RC11 out there. Updates for Broadlink debugging. Also, new Harmony library.
2018-01-30 17:47:44,735 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-01-30 17:47:44,735 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:44,745 [Thread-0] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - == Spark has ignited ...
2018-01-30 17:47:44,750 [Thread-0] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >> Listening on 0.0.0.0:80
2018-01-30 17:47:44.750:INFO:oejs.Server:Thread-0: jetty-9.4.z-SNAPSHOT
2018-01-30 17:47:44.770:INFO:oejs.session:Thread-0: DefaultSessionIdManager workerName=node0
2018-01-30 17:47:44.776:INFO:oejs.session:Thread-0: No SessionScavenger set, using defaults
2018-01-30 17:47:44.778:INFO:oejs.session:Thread-0: Scavenging every 600000ms
2018-01-30 17:47:44.840:INFO:oejs.AbstractConnector:Thread-0: Started ServerConnector@2e18d2{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-01-30 17:47:44.840:INFO:oejs.Server:Thread-0: Started @753ms
2018-01-30 17:47:49,752 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:49,752 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:47:49,752 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:54,752 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:54,753 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:47:54,753 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:59,756 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:47:59,757 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:47:59,757 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:04,762 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:04,763 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:04,763 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:09,766 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:09,767 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:09,767 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:14,770 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:14,770 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:14,770 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:19,771 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:19,771 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:19,771 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:24,775 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:24,775 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:24,775 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:29,778 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:29,778 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:29,778 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:34,780 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:34,780 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:34,780 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:39,781 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-30 17:48:40,242 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-30 17:48:40,258 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-01-30 17:48:40,258 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-01-30 17:48:40,267 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-01-30 17:48:40,277 [main] INFO com.bwssystems.HABridge.HABridge - Traceupnp: upnp config address: 192.168.0.100-useIface:false on web server: 0.0.0.0:80
2018-01-30 17:48:40,277 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-01-30 17:48:40,292 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-01-30 17:48:40,332 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: lo valid usable IP address: /127.0.0.1
2018-01-30 17:48:40,332 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding lo to our upnp join interface set.
2018-01-30 17:48:40,332 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: eth2 valid usable IP address: /192.168.0.100
2018-01-30 17:48:40,332 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding eth2 to our upnp join interface set.
2018-01-30 17:48:40,332 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
Bridge UI reports 1 broadlink found though commands are not received by the broadlink
@tuicemen You do not have debug on
Ok I thought I did.
Maybe I'm doing it wrong.
start bridge via command line go to debug and set broadlink to debug the reinitialize bridge is that not the right sequence?
That would be correct, make sure you hit the update log levels button though
after I set the debug? ok that's what I was missing.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-01-31 11:07:32.418:INFO:oejs.session:Thread-16: No SessionScavenger set, using defaults
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:32.418:INFO:oejs.session:Thread-16: Scavenging every 660000ms
2018-01-31 11:07:32,418 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:32,418 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:32.418:INFO:oejs.AbstractConnector:Thread-16: Started ServerConnector@81e45f{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-01-31 11:07:32.423:INFO:oejs.Server:Thread-16: Started @148039ms
2018-01-31 11:07:32,688 [qtp25724169-27] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:36,685 [qtp25724169-33] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:37,419 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:37,419 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:37,419 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:40,700 [qtp25724169-34] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:42,422 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:42,422 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:42,422 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:44,698 [qtp25724169-27] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:47,425 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:47,425 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:47,425 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:48,694 [qtp25724169-28] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:52,427 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:52,427 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:52,427 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:52,687 [qtp25724169-30] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:56,698 [qtp25724169-32] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:07:57,429 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:07:57,429 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:07:57,429 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:00,694 [qtp25724169-31] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:02,432 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:02,432 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:08:02,432 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:04,690 [qtp25724169-27] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:07,434 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:07,435 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:08:07,435 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:08,687 [qtp25724169-28] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:12,437 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:12,438 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:08:12,439 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:12,698 [qtp25724169-30] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:16,694 [qtp25724169-32] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:17,444 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:17,445 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:08:17,446 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:20,690 [qtp25724169-31] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 11:08:22,448 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 11:08:22,449 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 11:08:22,453 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-01-31 11:08:22,453 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-01-31 11:08:22,456 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-01-31 11:08:22,457 [main] INFO com.bwssystems.HABridge.HABridge - Traceupnp: upnp config address: 192.168.0.100-useIface:false on web server: 0.0.0.0:80
2018-01-31 11:08:22,457 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-01-31 11:08:22,473 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-01-31 11:08:22,508 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: lo valid usable IP address: /127.0.0.1
2018-01-31 11:08:22,508 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding lo to our upnp join interface set.
2018-01-31 11:08:22,513 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: eth2 valid usable IP address: /192.168.0.100
2018-01-31 11:08:22,513 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding eth2 to our upnp join interface set.
2018-01-31 11:08:22,513 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-01-31 11:08:24,701 [qtp25724169-27] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: request of description.xml from: 127.0.0.1:80 filled in with address: 192.168.0.100:80
I'm not sure what ports it is trying but according to a port scanner these are the ports open on the broadlink:
Service
Port 25 (TCP)
Port 110 (TCP)
Port 119 (TCP)
Port 143 (TCP)
Port 465 (TCP)
Port 563 (TCP)
Port 587 (TCP)
Port 993 (TCP)
Port 995 (TCP)
@tuicemen Well, something isn't right here, let me take a look
@tuicemen Found it, didn't end the check loop
@tuicemen So, anyway, it looks as the issue I found will not hurt your system and it will still give you the correct device. It will go through the discover 10 times before it stops. Turn on Debug and try again.
Also, I just broke down and bought an RM Mini and a Smartplug.... That's what those donations are for :)
Do I need a new version of the bridge? or just keep reinitializing till it finds a broadlink?
I put up a new version that fixes that, RC12. Go ahead and test with Debug
I looked at the mini but figured I had lots of RF devices so I went with the RM Pro.
here is the new output
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
2018-01-31 13:50:01.168:INFO:oejs.session:Thread-13: No SessionScavenger set, using defaults
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
2018-01-31 13:50:01.168:INFO:oejs.session:Thread-13: Scavenging every 660000ms2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-01-31 13:50:01.168:INFO:oejs.AbstractConnector:Thread-13: Started ServerConnector@c0711f{HTTP/1.1,[http/1.1]}{0.0.0.0:80}2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 13:50:01.168:INFO:oejs.Server:Thread-13: Started @82511ms
2018-01-31 13:50:01,168 [main] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Could not discover Broadlinks, Port in use, increasing by 11
2018-01-31 13:50:01,168 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-01-31 13:50:02,019 [qtp24864698-24] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 13:50:06,010 [qtp24864698-27] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-01-31 13:50:06,175 [main] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Adding Device to Map - host: 192.168.0.18, device Type: RM 2 Pro Plus, mac: 34:ffffffea:34:ffffffc7:ffffff91:3b
2018-01-31 13:50:06,175 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-01-31 13:50:06,175 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-01-31 13:50:06,180 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-01-31 13:50:06,180 [main] INFO com.bwssystems.HABridge.HABridge - Traceupnp: upnp config address: 192.168.0.100-useIface:false on web server: 0.0.0.0:80
2018-01-31 13:50:06,180 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-01-31 13:50:06,195 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-01-31 13:50:06,230 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: lo valid usable IP address: /127.0.0.1
2018-01-31 13:50:06,230 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding lo to our upnp join interface set.
2018-01-31 13:50:06,230 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Interface: eth2 valid usable IP address: /192.168.0.100
2018-01-31 13:50:06,230 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: Adding eth2 to our upnp join interface set.
2018-01-31 13:50:06,230 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-01-31 13:50:10,036 [qtp24864698-31] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: request of description.xml from: 127.0.0.1:80 filled in with address: 192.168.0.100:80
Just cosmetic but when switching Broadlink on the tab does appear only after switching tabs. Vice versa when switching it off it disappears when switching a tab.
Still the same - no visible activity on the RM2 mini
2018-01-31 20:34:43,021 [qtp1659618-49] DEBUG com.bwssystems.HABridge.hue.DeviceDataDecode - Request <<26007800581c111b0f0f0e0f2b2b100e0f0f100d0f0f0e0f100d0f0f0e0f100e1d0f0e100e0f0e1e1f000add571c111b0f0f110c2c2b100e0e0f100e0e0f0e0f100e0e0f0e0f110d1d100e0f0e0f0e1e1f000add571d0f1c100e100d2c2c0e0f0e0f0f0f0e0f0e0f110d0f0e100d100f1c100e0f0f0e0f1e1d000d05>>, not done: false
2018-01-31 20:34:43,021 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Authentication method starts
2018-01-31 20:34:43,021 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Constructing AuthCmdPayload
2018-01-31 20:34:43,022 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Sending CmdPacket with AuthCmdPayload: cmd=65 len=80
2018-01-31 20:34:43,023 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-01-31 20:34:43,023 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for headers
2018-01-31 20:34:43,023 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-01-31 20:34:43,036 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-01-31 20:34:43,036 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-01-31 20:34:43,037 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-01-31 20:34:43,038 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-01-31 20:34:43,039 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Received datagram
2018-01-31 20:34:43,040 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Getting encrypted data from 0x38 to the end
2018-01-31 20:34:43,040 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - encDataLen=32
2018-01-31 20:34:43,040 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Creating AES instance with initial iv, key
2018-01-31 20:34:43,040 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Decrypting encrypted data
2018-01-31 20:34:43,041 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Decrypted. len=32
2018-01-31 20:34:43,042 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Packet received payload bytes: 24A2D13AF8E0F075A22BEE015850EB91728CC6A395E9CD5D7F985468027E84C9
2018-01-31 20:34:43,042 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Getting key from 0x04 to 0x14
2018-01-31 20:34:43,042 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Packet received key bytes: F8E0F075A22BEE015850EB91728CC6A3
2018-01-31 20:34:43,042 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Getting ID from 0x00 to 0x04
2018-01-31 20:34:43,042 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - Packet received id bytes: 24A2D13A
2018-01-31 20:34:43,043 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - ID len=4
2018-01-31 20:34:43,043 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - End of authentication method
2018-01-31 20:34:43,044 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-01-31 20:34:43,044 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for headers
2018-01-31 20:34:43,044 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-01-31 20:34:43,046 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-01-31 20:34:43,047 [qtp1659618-49] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-01-31 20:34:43,056 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-01-31 20:34:43,057 [qtp1659618-49] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-01-31 20:34:43,058 [qtp1659618-49] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000089CDF9FF2A27E9033C5334EA34E3C0F024A2D13AA1C300000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
also no activity from the RM Pro
2018-01-31 15:13:34,738 [qtp24864698-25] INFO spark.http.matching.MatcherFilter - The requested route [/HNAP1/] has not been mapped in Spark for Accept: [*/*]
2018-01-31 15:16:15,764 [qtp24864698-31] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.18-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"260058000001289015101511143514111411141114111411143614351510153514351535143614351510151015101535141114111411141114351535143614111435153514351535140005160001284814000c490001284715000d05\"}"
2018-01-31 15:16:15,764 [qtp24864698-31] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
2018-01-31 15:16:15,769 [qtp24864698-31] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA550000000000000000000000000000000000000000000000007FDB00002A27E9034AAC34EA34C7913B06000000C6C40000E179EDB3525AC696E76A5DAEDF6C94376C25EADAF90C867B9ACD86D1623717EF000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
tried a RF send as well and get this:
2018-01-31 15:30:59,078 [qtp24864698-28] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - consolidating devices for Broadlink
2018-01-31 15:33:15,477 [qtp24864698-24] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.18-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"6e006e010d042800014f00011a8b12111234113511121b0811121e051112123312111211123412331234123412331212111212111112123312121211111212341134113412341211113511341135110004db00011a8c11121134123412111112111212111212113411121112113511341135113511341112111211121112123411121112121111351135113412331212113412341135110004db00011a8c11121135113412111211111211131112113411121211123411351134123411341211121112111212113411121212111211341135113412341112113511341234110004dc0001198d11121134113511121112111211121112113511121112113511341135113412341112111212111211123411121112111211351134123411341212113412341134120004db00011a8c11121135113411121112121211111211123411121112123411341234123411341211121112111212113412111211121112341234113412341112113511341234110005dc00000000000000000000\"}"
2018-01-31 15:33:15,478 [qtp24864698-24] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
2018-01-31 15:33:15,484 [qtp24864698-24] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA55000000000000000000000000000000000000000000000000C0CCF9FF2A27EE034DAC34EA34C7913B4EE14A724BC700000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
Thanks for those debug outputs. I need to look up and see if the data returns tell me anything.
@hphde Could you turn on TRACE at the root level and execute the IR command from the ha-bridge. The Broadlink Java API has Trace level calls.
@bwssytems it may sound weird but I can't set TRACE using the UI. Tried for all and the two broadlinks only but highest is DEBUG. Any other way to set it?
Trace doesn't appear to set for me either though the UI does say loggers were updated when I click update log levels.
Issuing a IR command displays no new info in the Commandline
Yep, seems that trace can't be set. Oh well, still working on what the reply means from multiple docs.
interestingly in the UI sending a IR command the Bridge states success even though the broadlink shows no activity with the LED.
If I use a bogus command in the send field then Bridge UI doesn't show an error or success but the command line does show a lot of info. I wonder if that may be helpful?
@tuicemen If you could share that command line info for the junk IR command, that would be great. It seems that the Broadlink is accepting the IR command but not executing the request properly. So there maybe a formatting issue when I call the broadlink java api.
Ok turned on debug for the broadlink then sent the bogus IR. The following is what is reported in the command line
2018-02-02 10:29:13,629 [qtp29459721-31] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.18-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"0000006D00220002015500AA0016001500160015001600400016001500160015001600150016001500160015001600400016004000160015001600400016004000160040001600400016004000160015001600150016001500160040001600150016001500160015001600150016004000160040001600400016001500160040001600400016004000160040001605E50155005500160E3C\"}"
2018-02-02 10:29:13,630 [qtp29459721-31] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
2018-02-02 10:29:13,660 [qtp29459721-31] ERROR com.github.mob41.blapi.pkt.CmdPacket - Cannot encrypt payload! Aborting
javax.crypto.IllegalBlockSizeException: Input length not multiple of 16 bytes
at com.sun.crypto.provider.CipherCore.finalNoPadding(CipherCore.java:1042)
at com.sun.crypto.provider.CipherCore.doFinal(CipherCore.java:1010)
at com.sun.crypto.provider.CipherCore.doFinal(CipherCore.java:847)
at com.sun.crypto.provider.AESCipher.engineDoFinal(AESCipher.java:446)
at javax.crypto.Cipher.doFinal(Cipher.java:2164)
at com.github.mob41.blapi.pkt.auth.AES.encrypt(AES.java:53)
at com.github.mob41.blapi.pkt.CmdPacket.<init>(CmdPacket.java:139)
at com.github.mob41.blapi.BLDevice.sendCmdPkt(BLDevice.java:551)
at com.github.mob41.blapi.BLDevice.sendCmdPkt(BLDevice.java:501)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.deviceHandler(BroadlinkHome.java:243)
at com.bwssystems.HABridge.hue.HueMulator.callUrl(HueMulator.java:1465)
at com.bwssystems.HABridge.hue.HueMulator.changeState(HueMulator.java:1220)
at com.bwssystems.HABridge.hue.HueMulator.lambda$39(HueMulator.java:444)
at spark.RouteImpl$1.handle(RouteImpl.java:72)
at spark.http.matching.Routes.execute(Routes.java:61)
at spark.http.matching.MatcherFilter.doFilter(MatcherFilter.java:130)
at spark.embeddedserver.jetty.JettyHandler.doHandle(JettyHandler.java:50)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1568)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at org.eclipse.jetty.server.Server.handle(Server.java:564)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:317)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.Invocable.invokePreferred(Invocable.java:128)
at org.eclipse.jetty.util.thread.Invocable$InvocableExecutor.invoke(Invocable.java:222)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:294)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:199)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:673)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:591)
at java.lang.Thread.run(Unknown Source)
2018-02-02 10:29:13,665 [qtp29459721-31] ERROR spark.http.matching.GeneralError -
com.github.mob41.blapi.ex.BLApiRuntimeException: Cannot encrypt payload
at com.github.mob41.blapi.pkt.CmdPacket.<init>(CmdPacket.java:145)
at com.github.mob41.blapi.BLDevice.sendCmdPkt(BLDevice.java:551)
at com.github.mob41.blapi.BLDevice.sendCmdPkt(BLDevice.java:501)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.deviceHandler(BroadlinkHome.java:243)
at com.bwssystems.HABridge.hue.HueMulator.callUrl(HueMulator.java:1465)
at com.bwssystems.HABridge.hue.HueMulator.changeState(HueMulator.java:1220)
at com.bwssystems.HABridge.hue.HueMulator.lambda$39(HueMulator.java:444)
at spark.RouteImpl$1.handle(RouteImpl.java:72)
at spark.http.matching.Routes.execute(Routes.java:61)
at spark.http.matching.MatcherFilter.doFilter(MatcherFilter.java:130)
at spark.embeddedserver.jetty.JettyHandler.doHandle(JettyHandler.java:50)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1568)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at org.eclipse.jetty.server.Server.handle(Server.java:564)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:317)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.Invocable.invokePreferred(Invocable.java:128)
at org.eclipse.jetty.util.thread.Invocable$InvocableExecutor.invoke(Invocable.java:222)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:294)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:199)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:673)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:591)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.crypto.IllegalBlockSizeException: Input length not multiple of 16 bytes
at com.sun.crypto.provider.CipherCore.finalNoPadding(CipherCore.java:1042)
at com.sun.crypto.provider.CipherCore.doFinal(CipherCore.java:1010)
at com.sun.crypto.provider.CipherCore.doFinal(CipherCore.java:847)
at com.sun.crypto.provider.AESCipher.engineDoFinal(AESCipher.java:446)
at javax.crypto.Cipher.doFinal(Cipher.java:2164)
at com.github.mob41.blapi.pkt.auth.AES.encrypt(AES.java:53)
at com.github.mob41.blapi.pkt.CmdPacket.<init>(CmdPacket.java:139)
... 26 common frames omitted
Hope that helps!
@tuicemen So, that means your payload is of the correct format as bytes and the correct length.
@tuiceman Also, I just noticed you have a \ on the end of yoru command that does not give errors. Remove that backslash.
the backslash is not present in the Bridge UI target item field at either end of the target item.
Ha-Bridge is adding that.
Doh, looking at the json... Sorry about that
AH darn! I thought maybe that was the issue
Alrighty, RC13 is up and I did make some changes to the Broadlink API subsystem that may help...or not... let's see
Same behaviour as before. No reaction on mini or pro, no TRACE possible
Same as before for me as well. :(
I received my rm mini 3 and a smart plug sp3, but I cannot even get those initialized with the app.
I found the econtrol app a bit confusing for non broadlink stuff.
I never tried their plugs but you would expect them to be simpler to setup.
Do you use the IHC App?
yes I have both. the web site says to use the econtrol app for setting up the sp3s
Well, I'll try again, thanks
Well, got the SP3 up and discovered. Will do some testing today on that, then need to get the rm mini up.
Awesome, the mini should be simple to setup, though I don't have one of those either.
My commands appear to go through to the bridge, but no light, or signal sent from the Broadlink. I'm sending RF. I've emailed my debug, and trace logs. Hope that helps.
HI, I am seeing the same symptoms ad quadhammer. it looks like the commands are going through but there is no indication from he Broadlink that anything has happened.
Here is some output from the cli:
2018-02-06 11:17:09,745 [qtp17738985-22] INFO spark.http.matching.MatcherFilter - The requested route [/api//lights/34/state] has not been mapped in Spark for Accept: [application/json, text/plain, /]
the command I am sending is this:
{"id":"172.16.0.24-2712","name":"RM 2","command":"ircommand","data":"2600c00173390e0f0e2a0f0d0f0d100f0f0d100d100e0e0e100c100e0e0e100e1028100f0e0d110d0e0e0f0d100d100f0e2a0f2a0f2a0f0c100d102a0f0e0e0e100d100e0f0e0f0d100d0f0e100c100e0f0e100d100d0f0f0e0d110d0f0e0f0d100d100e100c100e0e0f0f0d100d0f0e0f0e100f0e0d0f0e10281029100d100d0f0e0f0f100b1000015a7137100d10290f0d100d100d110d0f0e100e0e0d100d100e0f0d0f0e0f2b0f0e100c0f0f0e0d110c100e0f0d1128102b0e290f0d110c10290f0e0f0e0f0e0f0f0f0d110c0f0d100f0e0e0f0d110d0f0e100d10290e0e100d102811280f2a0f0f100c100d0f0d110c100e0f0d1029100f0e0d100d100d0f0d100e0f0e100d0f0e10290f29110c10280f0f0f290f2a0f2a0f0f0e0d100e0e2a1028100f0e0e0f0e0f0e100d0f0d0f0e0f0e100d0f0e100f0e0d110c1127102a0f290f0e100e0f0d110c0f0e100c100d0f0e100e0f29102a0f28100d110c0f0f0f0e0e0f0f0d100e0f0e0f0d100c110d0f0e0f0e100d0f0e100e0e29110c0f0f0f0e0e0f0e0f0f0e10290e0e0f0e0f0e0e0e100e0f0d100f100b100d0f0e100d0f0e0f0e100d100e0f0f0d2910290f2a0f290f0e100d1029102811000ca608000d050000000073390e0f"}
I have another system that sends these commands successfully but the json format is:
{ "api_id":134,
"command":"rm2_send",
"mac":"xx:xx:xx:xx:xx:xx",
"data":"2600c00173390e0f0e2a0f0d0f0d100f0f0d100d100e0e0e100c100e0e0e100e1028100f0e0d110d0e0e0f0d100d100f0e2a0f2a0f2a0f0c100d102a0f0e0e0e100d100e0f0e0f0d100d0f0e100c100e0f0e100d100d0f0f0e0d110d0f0e0f0d100d100e100c100e0e0f0f0d100d0f0e0f0e100f0e0d0f0e10281029100d100d0f0e0f0f100b1000015a7137100d10290f0d100d100d110d0f0e100e0e0d100d100e0f0d0f0e0f2b0f0e100c0f0f0e0d110c100e0f0d1128102b0e290f0d110c10290f0e0f0e0f0e0f0f0f0d110c0f0d100f0e0e0f0d110d0f0e100d10290e0e100d102811280f2a0f0f100c100d0f0d110c100e0f0d1029100f0e0d100d100d0f0d100e0f0e100d0f0e10290f29110c10280f0f0f290f2a0f2a0f0f0e0d100e0e2a1028100f0e0e0f0e0f0e100d0f0d0f0e0f0e100d0f0e100f0e0d110c1127102a0f290f0e100e0f0d110c0f0e100c100d0f0e100e0f29102a0f28100d110c0f0f0f0e0e0f0f0d100e0f0e0f0d100c110d0f0e0f0e100d0f0e100e0e29110c0f0f0f0e0e0f0e0f0f0e10290e0e0f0e0f0e0e0e100e0f0d100f100b100d0f0e100d0f0e0f0e100d100e0f0f0d2910290f2a0f290f0e100d1029102811000ca608000d050000000073390e0f"
}
I dont know if the structure makes a difference..
@peadard Which system do you use that is working. There still may be issues in the java api impl
@quadhammer The RF should be fine as it still may be an issue in the java api impl
Still cannot get my RM mini 3 setup with the app. I have heard that some of these units are defective and usually are returned for a new one.
I am still using this https://github.com/hphde/BlackBeanControl because of it's simplicity - maybe you'll have a look @bwssytems
./BlackBeanControl.py -c DISCOVER
Scanning network for Broadlink devices ...
Found 2 broadlink device(s)
Device 1:
IPAddress = 192.168.112.129
Port = 80
MACAddress = 34:ea:34:e3:c0:xy
Device 2:
IPAddress = 192.168.112.128
Port = 80
MACAddress = 34:ea:34:e7:e2:xy
BlackBeanControl.ini
[General]
IPAddress = 192.168.112.128
Port = 80
MACAddress = 34:ea:34:e7:e2:xy
Timeout = 6
[mini]
IPAddress = 192.168.112.129
Port = 80
MACAddress = 34:ea:34:e3:c0:xy
Timeout = 6
[Commands]
@bwssytems I use RM Commander Builder Tools which can be used to generate the command to send . http://leffab.wixsite.com/domo-assist
So, my sleuthing for the broadlink is down to the Authorization method is failing.
So, many things failed because the whole packet construction with encryption was a bit messed up. Try the new CR15
Just did a quick test will send debug later but the bridge fails to find my RM pro now. :(
Ok must be doing something wrong enabled broadlink debug but it shows no debug info:
2018-02-07 17:17:27,855 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-02-07 17:17:27,855 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-02-07 17:17:27,855 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-02-07 17:17:27,871 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-02-07 17:17:27.871:INFO:oejs.session:Thread-13: Scavenging every 600000ms
2018-02-07 17:17:27,886 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-02-07 17:17:27,886 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
It took a few seconds to discover the devices but it worked. But then I had weird effects. The pro was listed first - before it wasn't. I added something to the mini but when hit Build Item it appeared titled as RM 2 Pro Plus. I deleted that and retried with similar effect but the config was partially for the pro and the mini at the same time. After I deleted that, the devices disappeared in the tab but came back after a few seconds. Now the mini is listed first. Adding worked without problem. But unluckily it still doesn't work. TRACE also still doesn't.
did a bulk add even though no devices were found and got this:
2018-02-07 17:25:34,618 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-02-07 17:39:13,062 [qtp9740210-39] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - consolidating devices for Broadlink
2018-02-07 17:39:13,097 [qtp9740210-39] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-02-07 17:39:18,228 [qtp9740210-39] WARN com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Did not discover any Broadlinks.
This is with a total fresh Bridge install creating a new data base and different Broadlink rm pro I'm at my cottage will bring this Broadlink home with me so I can test with two.
With RC15 I get:
TODO: Incompatible decryption with non-16 multiple bytes. Forcing to have 2048 bytes
No other actions.
@quadhammer The link is at the top of this post
Ok, found a few things in the broadlink setup in the ha-bridge. Also, fixed the last outstanding AES item in the api lib. Also added a refresh button for broadlink and Lifx as those are broadcast based systems.
RC16 is out and is available from the link above
With Broadlink not enabled:
Exception in thread "main" java.lang.NullPointerException
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.broadlinkDiscover(BroadlinkHome.java:324)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.createHome(BroadlinkHome.java:64)
at com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome.<init>(BroadlinkHome.java:53)
at com.bwssystems.HABridge.HomeManager.buildHomes(HomeManager.java:128)
at com.bwssystems.HABridge.HABridge.main(HABridge.java:79)
Right after startup - and then opening the UI
2018-02-08 23:35:53,484 [qtp25770640-17] INFO spark.http.matching.MatcherFilter - The requested route [/api/devices/map/types] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-08 23:35:53,610 [qtp25770640-28] INFO spark.http.matching.MatcherFilter - The requested route [/api/devices] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-08 23:35:53,613 [qtp25770640-27] INFO spark.http.matching.MatcherFilter - The requested route [/api/devices/backup/available] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-08 23:35:53,760 [qtp25770640-14] INFO spark.http.matching.MatcherFilter - The requested route [/api] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
No Devices are listed. Broadlink Tab hitting refresh produces 404s:
Get broadlink Devices Error: undefined with status: Not Found - 404 and Refresh devices request Error: undefined with status: Not Found - 404
Well, that is unfortunate, won't get back to this for a few days
Ok must be doing something wrong enabled broadlink debug but it shows no debug info:
I had the same thing until I reinitialised the bridge with debug, and not trace.
RC16 can't find any of my devices, (same error as @hphde), but it can find the Broadlink.
@quadhammer The link is at the top of this post
I was using that file, and got the warning of: TODO: Incompatible decryption with non-16 multiple bytes. Forcing to have 2048 bytes
http://rm-bridge.fun2code.de/downloads/RMBridge-APIDocumentation.pdf is what I'm using at the moment, and it seems to have some documentation which might be helpful.
Today upgraded from 5.2.0 RC9 -> 5.2.0 RC16, my HA Bridge no longer shows any devices.
I have 124 devices in my data-file.
Instead, I get these error messages:
Get mapTypes Error: undefined with status: Not Found - 404
Cannot get devices from habridge: undefined with status: Not Found - 404
Cannot get devices from habridge: undefined with status: Not Found - 404
Cannot get devices from habridge: undefined with status: Not Found - 404
Reverted to RC9, 124 devices shown in HA Bridge again.
However, I still have the issue that Alexa with 5.2.0 RC* sometimes (not always) returns every command with:
"Device XY is not responding at the moment" (German: Das Ger盲t reagiert gerade nicht)
The command is executed correctly, though.
In earlier versions, I seldomly had this reponse.
Any solution for this?
I to get those error updating from RC13 to RC16.
interestingly I had tried a fresh install of RC16 on a laptop and not got those errors buit I also had no devices configured yet. The broadlink tab reported Broadlink Device List (4) but said none found although I had one RM Pro Running.
Well, I have a database and records from the start of testing and have not seen this issue? Will need to see command line log files.
New RC17 loaded. Should fix issues with broadlink discovery
Ill load 17 and see what happens tonight
Ok RC17 does fix the Broadlink discovery.
I turned on debug and tested adding a device but it always reverted to a device I had already added.
I attempted to copy the info but the Command line screen closed before I could copy. I'm not sure if this is a windows thing or the bridge is causing this. I never experienced this on builds prior to RC13.
I'll attempt to get a log again after I have a bite to eat.
I came close to getting a copy so I just need to be a little quicker hitting the copy. The Broadlink still does not respond to a send command But that could be that it is still loading old commands. I'll delete old Broadlink devices prior to debugging.
Ok it stayed open long enough this time.
I created and sent a command but no action from the broadlink
2018-02-13 18:46:15,019 [main] INFO com.bwssystems.HABridge.plugins.harmony.HarmonyHome - Harmony Home created. No Harmony devices configured.
2018-02-13 18:46:15,020 [main] INFO com.bwssystems.HABridge.plugins.NestBridge.NestHome - Nest Home created. No Nest configured.
2018-02-13 18:46:15,020 [main] INFO com.bwssystems.HABridge.plugins.hue.HueHome - Hue passthru Home created. No Hue passtrhu systems configured.
2018-02-13 18:46:15,020 [main] INFO com.bwssystems.HABridge.plugins.hal.HalHome - HAL Home created. No HAL devices configured.
2018-02-13 18:46:15,020 [main] INFO com.bwssystems.HABridge.plugins.mqtt.MQTTHome - MQTT Home created. No MQTT Clients configured.
2018-02-13 18:46:15,021 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
2018-02-13 18:46:15,021 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
2018-02-13 18:46:15,021 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
2018-02-13 18:46:15,021 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
2018-02-13 18:46:15.021:INFO:oejs.session:Thread-11: DefaultSessionIdManager workerName=node0
2018-02-13 18:46:15,021 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
2018-02-13 18:46:15.022:INFO:oejs.session:Thread-11: No SessionScavenger set, using defaults
2018-02-13 18:46:15,022 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
2018-02-13 18:46:15.022:INFO:oejs.session:Thread-11: Scavenging every 660000ms
2018-02-13 18:46:15,022 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
2018-02-13 18:46:15,023 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-02-13 18:46:15,023 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-02-13 18:46:15,023 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-02-13 18:46:15,023 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-02-13 18:46:15,024 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-02-13 18:46:15,024 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-02-13 18:46:15.025:INFO:oejs.AbstractConnector:Thread-11: Started ServerConnector@1123279{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-02-13 18:46:15.026:INFO:oejs.Server:Thread-11: Started @85391ms
2018-02-13 18:46:15,961 [qtp4187960-22] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-13 18:46:17,970 [qtp4187960-25] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-13 18:46:19,980 [qtp4187960-29] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-13 18:46:20,026 [main] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Adding Device to Map - host: 192.168.0.3, device Type: RM 2 Pro Plus, mac: 34:ffffffea:34:ffffffc7:ffffff91:3b
2018-02-13 18:46:20,026 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-02-13 18:46:20,044 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-02-13 18:46:20,044 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-02-13 18:46:20,045 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-02-13 18:46:20,046 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-02-13 18:46:20,070 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-02-13 18:46:20,137 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-02-13 18:46:49,590 [qtp4187960-23] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - consolidating devices for Broadlink
2018-02-13 18:47:19,807 [qtp4187960-25] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - consolidating devices for Broadlink
2018-02-13 18:48:38,515 [qtp4187960-25] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - consolidating devices for Broadlink
2018-02-13 18:49:00,981 [qtp4187960-22] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.3-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"260050000001279214111312143613121411131213121411143514361312143514361436143514361411141114111435141114111510141115351436143514111436133614361435150005160001274814000d050000000000000000\"}"
2018-02-13 18:50:13,958 [qtp4187960-22] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
2018-02-13 18:50:14,040 [qtp4187960-22] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000081D700002A27E903019334EA34C7913B000000001CC5000062483975D636AC99DD8197BC7572B859156B56FAF274FE7C18A34F3325353A6C000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
Tried RC 17 and getting the same results as @tuicemen.
i get a success msg but no lights on the broadlink.
Here are some of the logs:
>
2018-02-14 10:12:37,959 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.HueMulator - HueMulator PUT called on api/* with request <<>>, and body <<<{"on":true}>>>
2018-02-14 10:12:37,963 [qtp15791280-19] DEBUG spark.Request - matchedPart: :userid = 062e799a3d3a462c8c46b8ac677c2c2f
2018-02-14 10:12:37,975 [qtp15791280-19] DEBUG spark.Request - matchedPart: :id = 35
2018-02-14 10:12:37,978 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue state change requested: 062e799a3d3a462c8c46b8ac677c2c2f from 172.16.0.39 body: {"on":true}
2018-02-14 10:12:37,981 [qtp15791280-19] DEBUG com.bwssystems.HABridge.BridgeSecurity - validateWhitelistUser: found a user <062e799a3d3a462c8c46b8ac677c2c2f>
2018-02-14 10:12:37,984 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling on-off as requested.
2018-02-14 10:12:37,987 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.HueMulator - Decode Json for url items: [{"item":"{\"id\":\"172.16.0.24-2712\",\"name\":\"RM 2\",\"command\":\"ircommand\",\"data\":\"2600c00173390e0f0e2a0f0d0f0d100f0f0d100d100e0e0e100c100e0e0e100e1028100f0e0d110d0e0e0f0d100d100f0e2a0f2a0f2a0f0c100d102a0f0e0e0e100d100e0f0e0f0d100d0f0e100c100e0f0e100d100d0f0f0e0d110d0f0e0f0d100d100e100c100e0e0f0f0d100d0f0e0f0e100f0e0d0f0e10281029100d100d0f0e0f0f100b1000015a7137100d10290f0d100d100d110d0f0e100e0e0d100d100e0f0d0f0e0f2b0f0e100c0f0f0e0d110c100e0f0d1128102b0e290f0d110c10290f0e0f0e0f0e0f0f0f0d110c0f0d100f0e0e0f0d110d0f0e100d10290e0e100d102811280f2a0f0f100c100d0f0d110c100e0f0d1029100f0e0d100d100d0f0d100e0f0e100d0f0e10290f29110c10280f0f0f290f2a0f2a0f0f0e0d100e0e2a1028100f0e0e0f0e0f0e100d0f0d0f0e0f0e100d0f0e100f0e0d110c1127102a0f290f0e100e0f0d110c0f0e100c100d0f0e100e0f29102a0f28100d110c0f0f0f0e0e0f0f0d100e0f0e0f0d100c110d0f0e0f0e100d0f0e100e0e29110c0f0f0f0e0e0f0e0f0f0e10290e0e0f0e0f0e0e0e100e0f0d100f100b100d0f0e100d0f0e0f0e100d100e0f0f0d2910290f2a0f290f0e100d1029102811000ca608000d050000000073390e0f\"}","type":"broadlinkDevice"}]
2018-02-14 10:12:37,992 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling Home device handler for type : broadlinkDevice
2018-02-14 10:12:38,005 [qtp15791280-19] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"172.16.0.24-2712\",\"name\":\"RM 2\",\"command\":\"ircommand\",\"data\":\"2600c00173390e0f0e2a0f0d0f0d100f0f0d100d100e0e0e100c100e0e0e100e1028100f0e0d110d0e0e0f0d100d100f0e2a0f2a0f2a0f0c100d102a0f0e0e0e100d100e0f0e0f0d100d0f0e100c100e0f0e100d100d0f0f0e0d110d0f0e0f0d100d100e100c100e0e0f0f0d100d0f0e0f0e100f0e0d0f0e10281029100d100d0f0e0f0f100b1000015a7137100d10290f0d100d100d110d0f0e100e0e0d100d100e0f0d0f0e0f2b0f0e100c0f0f0e0d110c100e0f0d1128102b0e290f0d110c10290f0e0f0e0f0e0f0f0f0d110c0f0d100f0e0e0f0d110d0f0e100d10290e0e100d102811280f2a0f0f100c100d0f0d110c100e0f0d1029100f0e0d100d100d0f0d100e0f0e100d0f0e10290f29110c10280f0f0f290f2a0f2a0f0f0e0d100e0e2a1028100f0e0e0f0e0f0e100d0f0d0f0e0f0e100d0f0e100f0e0d110c1127102a0f290f0e100e0f0d110c0f0e100c100d0f0e100e0f29102a0f28100d110c0f0f0f0e0e0f0f0d100e0f0e0f0d100c110d0f0e0f0e100d0f0e100e0e29110c0f0f0f0e0e0f0e0f0f0e10290e0e0f0e0f0e0e0e100e0f0d100f100b100d0f0e100d0f0e0f0e100d100e0f0f0d2910290f2a0f290f0e100d1029102811000ca608000d050000000073390e0f\"}"
2018-02-14 10:12:38,012 [qtp15791280-19] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2
2018-02-14 10:12:38,015 [qtp15791280-19] DEBUG com.bwssystems.HABridge.hue.DeviceDataDecode - Request <<2600c00173390e0f0e2a0f0d0f0d100f0f0d100d100e0e0e100c100e0e0e100e1028100f0e0d110d0e0e0f0d100d100f0e2a0f2a0f2a0f0c100d102a0f0e0e0e100d100e0f0e0f0d100d0f0e100c100e0f0e100d100d0f0f0e0d110d0f0e0f0d100d100e100c100e0e0f0f0d100d0f0e0f0e100f0e0d0f0e10281029100d100d0f0e0f0f100b1000015a7137100d10290f0d100d100d110d0f0e100e0e0d100d100e0f0d0f0e0f2b0f0e100c0f0f0e0d110c100e0f0d1128102b0e290f0d110c10290f0e0f0e0f0e0f0f0f0d110c0f0d100f0e0e0f0d110d0f0e100d10290e0e100d102811280f2a0f0f100c100d0f0d110c100e0f0d1029100f0e0d100d100d0f0d100e0f0e100d0f0e10290f29110c10280f0f0f290f2a0f2a0f0f0e0d100e0e2a1028100f0e0e0f0e0f0e100d0f0d0f0e0f0e100d0f0e100f0e0d110c1127102a0f290f0e100e0f0d110c0f0e100c100d0f0e100e0f29102a0f28100d110c0f0f0f0e0e0f0f0d100e0f0e0f0d100c110d0f0e0f0e100d0f0e100e0e29110c0f0f0f0e0e0f0e0f0f0e10290e0e0f0e0f0e0e0e100e0f0d100f100b100d0f0e100d0f0e0f0e100d100e0f0f0d2910290f2a0f290f0e100d1029102811000ca608000d050000000073390e0f>>, not done: false
2018-02-14 10:12:38,027 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Authentication method starts
2018-02-14 10:12:38,029 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Constructing AuthCmdPayload
2018-02-14 10:12:38,031 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Sending CmdPacket with AuthCmdPayload: cmd=65 len=80
2018-02-14 10:12:38,046 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 0000000031313131313131313131313131313100000000000000000000000100000000000000000000000000000100005465737420203100000000000000000000000000000000000000000000000000
2018-02-14 10:12:38,050 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-02-14 10:12:38,053 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - count=27820 cmdPayload.cmd=65 payload.len=80
2018-02-14 10:12:38,055 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - New count: 27821 (added by 1)
2018-02-14 10:12:38,058 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating byte array with data
2018-02-14 10:12:38,060 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for un-encrypted payload
2018-02-14 10:12:38,063 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Un-encrypted payload checksum: c3a1
2018-02-14 10:12:38,075 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating AES instance with provided key [-45, -21, 73, 68, -22, 80, -84, 64, -124, 127, 127, 125, -27, 86, -65, -92], iv [86, 46, 23, -103, 109, 9, 61, 40, -35, -77, -70, 105, 90, 46, 111, 88]
2018-02-14 10:12:38,078 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-02-14 10:12:38,084 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - printBytes: 851739B35D15C0614B39AA0B0DDC5DC837E8160FDC216DA1229E3655A2FDE8A1806A436EF8ACC87A874FF86FC61E6652E6537B0190DD2A240DE735C6156D7D5474CC5A8513FEEAB89A9559E2F540643616DA720E534723A25D89B5413E1D18D0
2018-02-14 10:12:38,087 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypted. len=96
2018-02-14 10:12:38,095 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-02-14 10:12:38,097 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Whole packet checksum: c414
2018-02-14 10:12:38,100 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-02-14 10:12:38,102 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 5AA5AA555AA5AA5500000000000000000000000000000000000000000000000014C400002A276500AD6CB4430D391560DDD74C5BA1C30000851739B35D15C0614B39AA0B0DDC5DC837E8160FDC216DA1229E3655A2FDE8A1806A436EF8ACC87A874FF86FC61E6652E6537B0190DD2A240DE735C6156D7D5474CC5A8513FEEAB89A9559E2F540643616DA720E534723A25D89B5413E1D18D0
2018-02-14 10:12:38,115 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 172.16.0.24
2018-02-14 10:12:38,117 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-02-14 10:12:38,121 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Received datagram
2018-02-14 10:12:38,124 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 5AA5AA555AA5AA5500000000000000000000000000000000000000000000000069CBF9FF2A27EE03AA6CB4430D39156024A2D13A0EDD0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2018-02-14 10:12:38,129 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Getting encrypted data from 0x38 to the end
2018-02-14 10:12:38,134 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - encDataLen=1992
2018-02-14 10:12:38,145 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Creating AES instance with initial iv, key
2018-02-14 10:12:38,148 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2018-02-14 10:12:38,152 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Decrypting encrypted data
2018-02-14 10:12:38,175 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Decrypted. len=2000
2018-02-14 10:12:38,178 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Packet received payload bytes: 24A2D13AF8E0F075A22BEE015850EB91728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9
2018-02-14 10:12:38,186 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Getting key from 0x04 to 0x14
2018-02-14 10:12:38,189 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Packet received key bytes: F8E0F075A22BEE015850EB91728CC6A3
2018-02-14 10:12:38,195 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Getting ID from 0x00 to 0x04
2018-02-14 10:12:38,197 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - Packet received id bytes: 24A2D13A
2018-02-14 10:12:38,200 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - ID len=4
2018-02-14 10:12:38,215 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - End of authentication method
2018-02-14 10:12:38,218 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-02-14 10:12:38,221 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - count=27821 cmdPayload.cmd=6a payload.len=464
2018-02-14 10:12:38,223 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - New count: 27822 (added by 1)
2018-02-14 10:12:38,226 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating byte array with data
2018-02-14 10:12:38,228 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for un-encrypted payload
2018-02-14 10:12:38,231 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Un-encrypted payload checksum: dd0e
2018-02-14 10:12:38,234 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating AES instance with provided key [-8, -32, -16, 117, -94, 43, -18, 1, 88, 80, -21, -111, 114, -116, -58, -93], iv [86, 46, 23, -103, 109, 9, 61, 40, -35, -77, -70, 105, 90, 46, 111, 88]
2018-02-14 10:12:38,237 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-02-14 10:12:38,249 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - printBytes: 8381D112A4E0289786140C4AA19265F419A406D6ECB36D16C74D711A25E4B61C3DFA7CF6191988CB065C5665D385C5624D4A4E52C23D4436FEB3327A667259274064E50095CBA8EC3F9FA70340E5B066E1EBF041C767F22EAA9852197BCBE15B87FABD647D96432A0DC7930124EA5BFA3DC4A82AC4490E516D5E1C5AD756275ECF354180147D76AE34D11E7A8F8B2A283ED18FB777460BF58158B335C766B12C2CF69925306CE37892E9BC09BB506533CEFFE730FD350157BF8CC496FCBB3CED8B553985655F7D16F73D39E90BFD471D8E2EA5BA4C0C1125FA0DA7063CDA1D4E4CBFB24F5088346D055FFAEAE0642AB20465D4229A019D1FC814B0D3210398A0A6B0CB2CBAA67553E12EAB8087E28F99864D25017D50B803BD30A713556DB9855B63683A3CA9ED05DB3EF4188DDE19C45BC053AACBDF25D2C50705CAE2F5EA1EACE20B4DD70A468CF6B1569D10C3F516C62554D90B8103585FB3FEE370B52049D5148B6BA3F3544FFFC574D46BE57CEA4C967FC3062384CCC2286DB211126CA32A02B6F61B49B2D081C8E5AAC96C9581879253F723F39FF873B5A0696BA9D2AC61E8998322872582337B5D83273A6102CE383203A0FD488DDDC68250484E197420EEB5B52BFA4B15B9E12FBA25A8212AF54EDC96AC93FF63679EF983194523ED
2018-02-14 10:12:38,255 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypted. len=480
2018-02-14 10:12:38,263 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-02-14 10:12:38,266 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Whole packet checksum: be30
2018-02-14 10:12:38,269 [qtp15791280-19] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-02-14 10:12:38,271 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 5AA5AA555AA5AA5500000000000000000000000000000000000000000000000030BE00002A276A00AE6CB4430D39156024A2D13A0EDD00008381D112A4E0289786140C4AA19265F419A406D6ECB36D16C74D711A25E4B61C3DFA7CF6191988CB065C5665D385C5624D4A4E52C23D4436FEB3327A667259274064E50095CBA8EC3F9FA70340E5B066E1EBF041C767F22EAA9852197BCBE15B87FABD647D96432A0DC7930124EA5BFA3DC4A82AC4490E516D5E1C5AD756275ECF354180147D76AE34D11E7A8F8B2A283ED18FB777460BF58158B335C766B12C2CF69925306CE37892E9BC09BB506533CEFFE730FD350157BF8CC496FCBB3CED8B553985655F7D16F73D39E90BFD471D8E2EA5BA4C0C1125FA0DA7063CDA1D4E4CBFB24F5088346D055FFAEAE0642AB20465D4229A019D1FC814B0D3210398A0A6B0CB2CBAA67553E12EAB8087E28F99864D25017D50B803BD30A713556DB9855B63683A3CA9ED05DB3EF4188DDE19C45BC053AACBDF25D2C50705CAE2F5EA1EACE20B4DD70A468CF6B1569D10C3F516C62554D90B8103585FB3FEE370B52049D5148B6BA3F3544FFFC574D46BE57CEA4C967FC3062384CCC2286DB211126CA32A02B6F61B49B2D081C8E5AAC96C9581879253F723F39FF873B5A0696BA9D2AC61E8998322872582337B5D83273A6102CE383203A0FD488DDDC68250484E197420EEB5B52BFA4B15B9E12FBA25A8212AF54EDC96AC93FF63679EF983194523ED
2018-02-14 10:12:38,275 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 172.16.0.24
2018-02-14 10:12:38,277 [qtp15791280-19] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-02-14 10:12:38,281 [qtp15791280-19] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000069CBF9FF2A27EE03AA6CB4430D39156024A2D13A0EDD00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
2018-02-14 10:12:43,184 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - sendUpnpNotify notifyTemplate is <<HOST: 239.255.255.250:1900
CACHE-CONTROL: max-age=100
LOCATION: http://172.16.0.17:80/description.xml
SERVER: Linux/3.14.0 UPnP/1.0 IpBridge/1.19.0
NTS: ssdp:alive
hue-bridgeid:
NT: uuid:2f402f80-da50-11e1-9b23-b827eb4ad5b1
USN: uuid:2f402f80-da50-11e1-9b23-b827eb4ad5b1
>
Ok, I may not have had the latest api handler included. Try RC18.
Still same results :(
Bridge reports sent successful but Broadlink LED does not blink or send the command.
Do we need to recreate the device or are the send strings still the same?
Me too - discovery works, DEBUG shows in/out but no action from the Broadlink device
I need you to turn on DEBUG for broadlink Home and the broadlink api items and post your command line logs.
2018-02-14 17:19:42,761 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.HueMulator - HueMulator PUT called on api/* with request <
<</api/4b672858de25495da1ec3530b00bd160/lights/27/state>>>, and body <<<{"on":true}>>>
2018-02-14 17:19:42,762 [qtp3715863-35] DEBUG spark.Request - matchedPart: :userid = 4b672858de25495da1ec3530b00bd160
2018-02-14 17:19:42,763 [qtp3715863-35] DEBUG spark.Request - matchedPart: :id = 27
2018-02-14 17:19:42,763 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue state change requested: 4b672858de25495da
1ec3530b00bd160 from 192.168.112.21 body: {"on":true}
2018-02-14 17:19:42,764 [qtp3715863-35] DEBUG com.bwssystems.HABridge.BridgeSecurity - validateWhitelistUser: found a user <4b672858
de25495da1ec3530b00bd160>
2018-02-14 17:19:42,765 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling on-off as requested.
2018-02-14 17:19:42,766 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.HueMulator - Decode Json for url items: [{"item":"{\"id\":
\"192.168.112.129-2737\",\"name\":\"RM Mini\",\"command\":\"ircommand\",\"data\":\"26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0
f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000\"}","type":"broadlinkDevice"}]
2018-02-14 17:19:42,768 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.HueMulator - Calling Home device handler for type : broadlinkDevice
2018-02-14 17:19:42,768 [qtp3715863-35] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.112.129-2737\",\"name\":\"RM Mini\",\"command\":\"ircommand\",\"data\":\"26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000\"}"
2018-02-14 17:19:42,769 [qtp3715863-35] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM Mini
2018-02-14 17:19:42,770 [qtp3715863-35] DEBUG com.bwssystems.HABridge.hue.DeviceDataDecode - Request <<26008400581d0e1e0f0e0f0f0f1c1c0f0f0e100e0f0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f1d100e1d100d10000acf571d0f1d0f0e0f0f0f1d1c0e100e0f0f0e0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e1d100e1e0f0f0e000ad0561d101c0f0e0f0f101c1c0f0f0f0e0f0e0f0f0f0e0f0f0e0f0f0f0e100e0f0e0f0f1d0f0f1d0f0f0e000d0500000000>>, not done: false
2018-02-14 17:19:42,771 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Authentication method starts
2018-02-14 17:19:42,771 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Constructing AuthCmdPayload
2018-02-14 17:19:42,771 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Sending CmdPacket with AuthCmdPayload: cmd=65 len=80
2018-02-14 17:19:42,771 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth AuthPayload initial bytes to send: 0000000031313131313131313131313131313100000000000000000000000100000000000000000000000000000100005465737420203100000000000000000000000000000000000000000000000000
2018-02-14 17:19:42,772 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-02-14 17:19:42,772 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - count=32540 cmdPayload.cmd=65 payload.len=80
2018-02-14 17:19:42,773 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - New count: 32541 (added by 1)
2018-02-14 17:19:42,773 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating byte array with data
2018-02-14 17:19:42,773 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for un-encrypted payload
2018-02-14 17:19:42,773 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Un-encrypted payload checksum: c3a1
2018-02-14 17:19:42,773 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating AES instance with provided key [-8, -32, -16, 117, -94, 43, -18, 1, 88, 80, -21, -111, 114, -116, -58, -93], iv [86, 46, 23, -103, 109, 9, 61, 40, -35, -77, -70, 105, 90, 46, 111, 88]
2018-02-14 17:19:42,774 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-02-14 17:19:42,780 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - printBytes: 2E88BCE3858A1A70893FCB792171D8B466E2E3658B7C40E73A8FCE4090C9A7AFEF5F42106985C6FE3BB6B324CD8B3B7ED8691547F426B7DB8EB7D0C6215FC67031860C59869F2354945F07837632606E1372046F1D47250CEF2A7B6678C7E922
2018-02-14 17:19:42,781 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypted. len=96
2018-02-14 17:19:42,781 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-02-14 17:19:42,781 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Whole packet checksum: c0f4
2018-02-14 17:19:42,781 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-02-14 17:19:42,782 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 5AA5AA555AA5AA55000000000000000000000000000000000000000000000000F4C000002A2765001D7F34EA34E3C0F024A2D13AA1C300002E88BCE3858A1A70893FCB792171D8B466E2E3658B7C40E73A8FCE4090C9A7AFEF5F42106985C6FE3BB6B324CD8B3B7ED8691547F426B7DB8EB7D0C6215FC67031860C59869F2354945F07837632606E1372046F1D47250CEF2A7B6678C7E922
2018-02-14 17:19:42,784 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-02-14 17:19:42,784 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-02-14 17:19:42,785 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Received initial datagram
2018-02-14 17:19:42,785 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth recv data bytes after initial req: 5AA5AA555AA5AA5500000000000000000000000000000000000000000000000084CDF9FF2A27E903177F34EA34E3C0F06F0CBA90A1C30000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2018-02-14 17:19:42,786 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Getting encrypted data from 0x38 to the end
2018-02-14 17:19:42,786 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth encDataLen=1992
2018-02-14 17:19:42,787 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth padded encoded bytes from initial req: 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
2018-02-14 17:19:42,787 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Creating AES instance with initial iv, key
2018-02-14 17:19:42,787 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Decrypting encrypted data
2018-02-14 17:19:42,793 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Decrypted. len=2000
2018-02-14 17:19:42,793 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Packet received payload bytes: 24A2D13AF8E0F075A22BEE015850EB91728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9728CC6A395E9CD5D7F985468027E84C9
2018-02-14 17:19:42,794 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Getting key from 0x04 to 0x14
2018-02-14 17:19:42,794 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Packet received key bytes: F8E0F075A22BEE015850EB91728CC6A3
2018-02-14 17:19:42,794 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Getting ID from 0x00 to 0x04
2018-02-14 17:19:42,794 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth Packet received id bytes: 24A2D13A
2018-02-14 17:19:42,795 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth ID len=4
2018-02-14 17:19:42,795 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - auth End of authentication method
2018-02-14 17:19:42,795 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Constructor CmdPacket starts
2018-02-14 17:19:42,796 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - count=32541 cmdPayload.cmd=6a payload.len=144
2018-02-14 17:19:42,802 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - New count: 32542 (added by 1)
2018-02-14 17:19:42,803 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating byte array with data
2018-02-14 17:19:42,803 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running checksum for un-encrypted payload
2018-02-14 17:19:42,803 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Un-encrypted payload checksum: c70e
2018-02-14 17:19:42,804 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Creating AES instance with provided key [-8, -32, -16, 117, -94, 43, -18, 1, 88, 80, -21, -111, 114, -116, -58, -93], iv [86, 46, 23, -103, 109, 9, 61, 40, -35, -77, -70, 105, 90, 46, 111, 88]
2018-02-14 17:19:42,804 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypting payload
2018-02-14 17:19:42,806 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - printBytes: 73D40E65DE1CC77B3E725FF8613AB84B1CE3FDDB1AA9328F27BF43C2C144A683C8999942857947E1D9275348996691493C2E09B93AA8F7E0E98D66C14D7039C6F19666A1296F5D5ED9ACD1FDA975D2A3B1C587BFBC5870526C7FD59C8A329317EF20CC16B5AA43C39C097D803B8360A6D09AE898F75F1167F374588C5F2223C69ECE2258C77D28787E1A4D44F4ECBD8F5DF07A2EB3BBFF13FB515C42FD4C3985
2018-02-14 17:19:42,806 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Encrypted. len=160
2018-02-14 17:19:42,806 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Running whole packet checksum
2018-02-14 17:19:42,807 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - Whole packet checksum: c17a
2018-02-14 17:19:42,807 [qtp3715863-35] DEBUG com.github.mob41.blapi.pkt.CmdPacket - End of CmdPacket constructor
2018-02-14 17:19:42,807 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - printBytes: 5AA5AA555AA5AA550000000000000000000000000000000000000000000000007AC100002A276A001E7F34EA34E3C0F024A2D13A0EC7000073D40E65DE1CC77B3E725FF8613AB84B1CE3FDDB1AA9328F27BF43C2C144A683C8999942857947E1D9275348996691493C2E09B93AA8F7E0E98D66C14D7039C6F19666A1296F5D5ED9ACD1FDA975D2A3B1C587BFBC5870526C7FD59C8A329317EF20CC16B5AA43C39C097D803B8360A6D09AE898F75F1167F374588C5F2223C69ECE2258C77D28787E1A4D44F4ECBD8F5DF07A2EB3BBFF13FB515C42FD4C3985
2018-02-14 17:19:42,807 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - DESTIP: 192.168.112.129
2018-02-14 17:19:42,808 [qtp3715863-35] DEBUG com.github.mob41.blapi.BLDevice - DESTPORT: 80
2018-02-14 17:19:42,808 [qtp3715863-35] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000084CDF9FF2A27E903177F34EA34E3C0F06F0CBA90A1C300000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
> 2018-02-14 11:31:48,173 [Thread-10] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - done
> 2018-02-14 11:31:53,163 [main] INFO com.bwssystems.HABridge.BridgeSettings - reading from config file: data/habridge.config
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.HABridge - HA Bridge (v5.2.0RC18) initializing....
> 2018-02-14 11:31:56,689 [main] INFO spark.staticfiles.StaticFilesConfiguration - StaticResourceHandler configured with folder = /public
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.SystemControl - System control service started....
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - Initializing UDP response Socket...
> 2018-02-14 11:31:56,689 [Thread-11] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - == Spark has ignited ...
> 2018-02-14 11:31:56,689 [Thread-11] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >> Listening on 0.0.0.0:80
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - UDP response Socket initialized to: 50000
> 2018-02-14 11:31:56.689:INFO:oejs.Server:Thread-11: jetty-9.4.z-SNAPSHOT
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.http.HTTPHome - HTTP Home created.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.harmony.HarmonyHome - Harmony Home created. No Harmony devices configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.NestBridge.NestHome - Nest Home created. No Nest configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.hue.HueHome - Hue passthru Home created. No Hue passtrhu systems configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.hal.HalHome - HAL Home created. No HAL devices configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.mqtt.MQTTHome - MQTT Home created. No MQTT Clients configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
> 2018-02-14 11:31:56,689 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
> 2018-02-14 11:31:56.694:INFO:oejs.session:Thread-11: DefaultSessionIdManager workerName=node0
> 2018-02-14 11:31:56.694:INFO:oejs.session:Thread-11: No SessionScavenger set, using defaults
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
> 2018-02-14 11:31:56.694:INFO:oejs.session:Thread-11: Scavenging every 660000ms
> 2018-02-14 11:31:56,694 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
> 2018-02-14 11:31:56.694:INFO:oejs.AbstractConnector:Thread-11: Started ServerConnector@15b99c6{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
> 2018-02-14 11:31:56.694:INFO:oejs.Server:Thread-11: Started @105428ms
> 2018-02-14 11:31:58,201 [qtp6417098-22] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
> 2018-02-14 11:32:00,222 [qtp6417098-25] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
> 2018-02-14 11:32:01,694 [main] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Adding Device to Map - host: 192.168.0.3, device Type: RM 2 Pro Plus, mac: 34:ffffffea:34:ffffffc7:ffffff91:3b
> 2018-02-14 11:32:01,694 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
> 2018-02-14 11:32:01,709 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
> 2018-02-14 11:32:01,709 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
> 2018-02-14 11:32:01,709 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
> 2018-02-14 11:32:01,714 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
> 2018-02-14 11:32:01,739 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
> 2018-02-14 11:32:01,800 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
> 2018-02-14 11:33:10,621 [qtp6417098-28] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.3-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"260096006f6d1d521d511e1b1d511d521d511e511d521d1b1d1b1d1b1d1b1d1a1d521d1b1d1b1d1b1d1b1d1b1d511d521d521d0004cd6f6d1e511d521d1b1d511d521d521d511d521d1b1d1b1d1b1d1b1d1b1c521d1b1d1b1d1b1d1b1d1b1d511d521d521d0004cd706c1d521d521d1b1d511d521d521d511d521d1b1d1b1d1b1d1b1d1b1d511d1b1d1b1d1b1d1b1d1b1d521d511d521d000d050000\"}"
> 2018-02-14 11:33:10,626 [qtp6417098-28] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
> 2018-02-14 11:33:10,741 [qtp6417098-28] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000098CAF9FF2A27EE03621B34EA34C7913B0600000075D600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
Well, I'll need to review the auth code some more, seems the returns are not decoded properly
Not sure if this is relevant, I've played with adding different info in the name and command parts of the send strings and this has no affect on the returns in the command line. it still reports a RM2 Device data return info
@tuicemen I should specify it is the return from getting the auth key which is farther up the chain.
@bwssytems I suspected as much. Changing the ID or data results in a failed send. I'm just playing around hoping to stumble on something which may be of value.
I made a tcpdump for one request using BlackBeanControl and one with HA-Bridge. Both where supposed to send the exact same request. You can open them in Wireshark.
What immediatly springs into the eye is that it looks like HA-Bridge is sending two requests instead of just one.
I made another tcpdump with BlackBeanControl, same request, just to show what can vary with each request.
I had thought of doing something simular but it has been so long since using wireshark I'd have to learn it all over again.
Thanks @hphde Will take a look at the dump
So, found the duplicate send and added more debug clarity. RC 19 is up. Please run debug for com.bwssystems.HABridge.plugins.broadlink and com.github.mob41. Thanks
I can confirm the dupes are gone but still no sign of sending signals from the broadlink device.
tcpdump.zip
@hphde If you could turn on the debug items I stated above and post the command line log, that would help.
tcpdump & debug of the same request
tcpdump-debuglog.zip
Ok, reverted a change in the auth code. RC20 is up. IF this doesn't work, I will need to really dig into it as it looks at one point I had the correct auth logic running.
I still only see RC19 available
whoops, uodated
2018-02-16 17:58:11,145 [main] INFO com.bwssystems.HABridge.BridgeSettings - reading from config file: data/habridge.config
2018-02-16 17:58:11,219 [main] INFO com.bwssystems.HABridge.HABridge - HA Bridge (v5.2.0RC19) initializing....
2018-02-16 17:58:11,219 [main] INFO spark.staticfiles.StaticFilesConfiguration - StaticResourceHandler configured with folder = /public
2018-02-16 17:58:11,220 [main] INFO com.bwssystems.HABridge.SystemControl - System control service started....
2018-02-16 17:58:11,221 [Thread-16] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - == Spark has ignited ...
2018-02-16 17:58:11,221 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - Initializing UDP response Socket...
2018-02-16 17:58:11,221 [Thread-16] INFO spark.embeddedserver.jetty.EmbeddedJettyServer - >> Listening on 0.0.0.0:80
2018-02-16 17:58:11,221 [main] INFO com.bwssystems.HABridge.util.UDPDatagramSender - UDP response Socket initialized to: 50000
2018-02-16 17:58:11,221 [main] INFO com.bwssystems.HABridge.plugins.http.HTTPHome - HTTP Home created.
2018-02-16 17:58:11,221 [main] INFO com.bwssystems.HABridge.plugins.harmony.HarmonyHome - Harmony Home created. No Harmony devices configured.
2018-02-16 17:58:11.221:INFO:oejs.Server:Thread-16: jetty-9.4.z-SNAPSHOT2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.NestBridge.NestHome - Nest Home created. No Nest configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.hue.HueHome - Hue passthru Home created. No Hue passtrhu systems configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.hal.HalHome - HAL Home created. No HAL devices configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.mqtt.MQTTHome - MQTT Home created. No MQTT Clients configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.hass.HassHome - HomeAssistant Home created. No HomeAssistants configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.homewizard.HomeWizardHome - HomeWizard Home created. No HomeWizard gateways configured.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.exec.CommandHome - Command Home for system program execution created.
2018-02-16 17:58:11,222 [main] INFO com.bwssystems.HABridge.plugins.tcp.TCPHome - TCP Home created.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.udp.UDPHome - UDP Home created.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.vera.VeraHome - Vera Home created. No Veras configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.fibaro.FibaroHome - Fibaro Home created. No Fibaros configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.domoticz.DomoticzHome - Domoticz Home created. No Domoticz devices configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.somfy.SomfyHome - Somfy Home created. No Somfys configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.lifx.LifxHome - LifxDevice Home created. No LifxDevices configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.openhab.OpenHABHome - OpenHAB Home created. No OpenHABs configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.fhem.FHEMHome - FHEM Home created. No FHEMs configured.
2018-02-16 17:58:11,223 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink discover....
2018-02-16 17:58:11.224:INFO:oejs.session:Thread-16: DefaultSessionIdManager workerName=node0
2018-02-16 17:58:11.224:INFO:oejs.session:Thread-16: No SessionScavenger set, using defaults
2018-02-16 17:58:11.224:INFO:oejs.session:Thread-16: Scavenging every 660000ms
2018-02-16 17:58:11.226:INFO:oejs.AbstractConnector:Thread-16: Started ServerConnector@231e3d{HTTP/1.1,[http/1.1]}{0.0.0.0:80}
2018-02-16 17:58:11.226:INFO:oejs.Server:Thread-16: Started @92738ms
2018-02-16 17:58:12,498 [qtp9968991-27] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-16 17:58:14,507 [qtp9968991-30] INFO spark.http.matching.MatcherFilter - The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, */*]
2018-02-16 17:58:16,225 [main] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Adding Device to Map - host: 192.168.0.3, device Type: RM 2 Pro Plus, mac: 34:ffffffea:34:ffffffc7:ffffff91:3b
2018-02-16 17:58:16,225 [main] INFO com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - Broadlink Home created.
2018-02-16 17:58:16,245 [main] WARN com.bwssystems.HABridge.dao.GroupRepository - Error reading the file: data\group.db - Does not exist or is not readable. continuing...
2018-02-16 17:58:16,246 [main] INFO com.bwssystems.HABridge.devicemanagmeent.DeviceResource - HABridge device management service started....
2018-02-16 17:58:16,247 [main] INFO com.bwssystems.HABridge.hue.HueMulator - Hue emulator service started....
2018-02-16 17:58:16,248 [main] INFO com.bwssystems.HABridge.upnp.UpnpSettingsResource - Description xml service started....
2018-02-16 17:58:16,272 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener starting....
2018-02-16 17:58:16,340 [main] INFO com.bwssystems.HABridge.upnp.UpnpListener - UPNP Discovery Listener running and ready....
2018-02-16 17:58:50,485 [qtp9968991-34] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - executing HUE api request to send message to BroadlinkDevice: "{\"id\":\"192.168.0.3-272a\",\"name\":\"RM 2 Pro Plus\",\"command\":\"ircommand\",\"data\":\"260096006f6d1d521d511e1b1d511d521d511e511d521d1b1d1b1d1b1d1b1d1a1d521d1b1d1b1d1b1d1b1d1b1d511d521d521d0004cd6f6d1e511d521d1b1d511d521d521d511d521d1b1d1b1d1b1d1b1d1b1c521d1b1d1b1d1b1d1b1d1b1d511d521d521d0004cd706c1d521d521d1b1d511d521d521d511d521d1b1d1b1d1b1d1b1d1b1d511d1b1d1b1d1b1d1b1d1b1d521d511d521d000d050000\"}"
2018-02-16 17:58:50,486 [qtp9968991-34] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - calling BroadlinkDevice: RM 2 Pro Plus
2018-02-16 17:58:50,651 [qtp9968991-34] DEBUG com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome - RM2 Device data return: <<<5AA5AA555AA5AA5500000000000000000000000000000000000000000000000098CBF9FF2A27EE038FEE34EA34C7913B0600000075D600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000>>>
Nothing sent from Broadlink No LED light flicker either.
tcpdump-debuglog.zip
Same procedere as every year James
For information - regarding my problem "HA bridge device list empty":
This problem was gone again with RC17.
Having experienced this with an old RC build my self on a Windows 10 laptop. I decided to download and test this version on it as well.
Prior to downloading I noticed my java was very outdated So this may have been my original issue.
On starting all my devices were seen however no Broadlinks were.
I renamed my old Bridge configuration file then started the bridge again this time it found a Broadlink device. So My issue looks like it may have been an outdated Java and configuration file.
Alright, RC22 out there and I think I have it replying properly for RM devices. I'm having issues with SP devices currently. Give it a go
No luck for me on the RM front. No error message, but no workie either. I noticed on my other bridge that a MAC addresss is used, not just IP. Dunno what I'm talking about, but just thought I'd mention it.
Cheers.
Yep, tried it at home and nothing either
@bwssytems it wants user/password? I have never setup security
@hphde Something must have changed in your settings. It should not have changed.
So far in my debugging and using the python library as my test base. It seems that the datagram message from Java is not being responded too even though it is setup the same as the python call. Doing alot of network tracing to resolve.
Well found the issue. packet checksum calculator is not working.
Ok, found that java bytes don't behave the same way in python. Needed to ensure unsigned values for checksum. Let's give this a try. RC23 is up
it works for me!!!!!!!
Yahooo!
@bwssytems great job!
Now I just need to test with the rest of my devices to make sure everything else is working and add a few more IR devices.
I'll also test some RF devices
Ok RF devices work as well!
I messed up the RF code as it had %22%7D from an HTTP link. It works for me too now, thanks.
I can confirm partial success. IR commands now work for me. With RF I had partial success. One was working, the other not. The one that wasn't working didn't show any error on the UI. Then I realized the JSON request received a 500.
2018-03-06 09:46:48,794 [qtp12509699-14] ERROR com.github.mob41.blapi.pkt.CmdPacket - Cannot encrypt payload! Aborting
javax.crypto.IllegalBlockSizeException: Input length not multiple of 16 bytes
The working codes had a length of 728 = 4516+8, the failing ones of 468 = 2916+4. I added four zeros and et'voila, they also worked.
For the user/password thing I rolled back to 5.1.0 which gave me no user, then I added a user. After that I was able to use RC23 with user and password. After deleting the user everything was fine again.
I found using the add Broadlink device adds some unneeded info for the device.
I'm able edit the device and remove the ON/Off/dim item type and the map id and the device still works.
After some debugging today, I believe I have it all working.
To make sure the new version works properly, you will need to delete and recreate your devices with the builder. More information is needed in the device URLs to guarantee it is working.
RC24 is up
I deleted my broadlink device, and recreated it, and it works well to send my RF, but device discovery in Alexa no longer works. Went back to 5.1.0 and they all show up again.
I am not having any issues with discovery in RC24. @tuicemen Can you discover?
@hdhpe I did find an issue with the padding for encryption. You won't have to manually add anything next time.
@bwssytems so padding doesn't work. The good thing: An error appears in the GUI instead of just a 500 in the background.
For discovery I have the same as @quadhammer - the newly added device doesn't appear
Please turn on trace upnp
I have no issues with discovery
@hphde I assume your echo is at 192.168.112.49?
@hdhpe I also changed the description xml to not return an empty service list and added a delay between upnp sends. You may want to lower the delay time in the bridge settings.
@bwssytems .160, .159, .49 and .29
Changed UPNP Send Delay from 1500 to 1000 - that did the job. Device was discovered
RC25 is up, should be good with broadlink now
Well, not perfect yet....
Alright uploaded new RC25
I still have to kick in discovery twice in a row. What's recommended for UPNP Send Delay?
@hdhpe This was found by another user and as the spec shows it is a number between 500 milis and 1.5 secs for upnp. I would go down to 500 to see where that gets you.
The Broadlink Tab has disappeared, but Alexa can discover the Bridge's devices again now. I set UPNP Send Delay to '500'.
`
INFO | Broadlink discover.... | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
-- | -- | --
03-08-2018 10:32:42.817 | INFO | The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, /] | spark.http.matching.MatcherFilter
03-08-2018 10:32:44.839 | INFO | The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, /] | spark.http.matching.MatcherFilter
03-08-2018 10:32:46.854 | INFO | The requested route [/description.xml] has not been mapped in Spark for Accept: [application/json, text/plain, /] | spark.http.matching.MatcherFilter
03-08-2018 10:32:47.601 | INFO | Broadlink discover found 1 clients. | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
03-08-2018 10:32:47.601 | INFO | Broadlink Home created. No Broadlinks configured. | com.bwssystems.HABridge.plugins.broadlink.BroadlinkHome
`
650 seems to be "it" for me. 700 was still not working.
@quadhammer In your 'Bridge Control' tab, do you have "Broadlink Support" checked?
It seems I forgot to put in the validation to not discover if it was not checked. The Broadlink tab is controlled by this setting as well.
@hphde I think I will default the upnp delay to be 650 as @quadhammer had tuned it down and it is working as well. 1000 may be too high.
Released 5.2.0, closing this thread.
I also tried 100ms, this at least solved the issue "I don't know what went wrong".
Also, I moved HA Bridge to a dedicated Raspberry Pi Zero W that is only running HA Bride on Raspbian Stretch. On my other raspbian, I also had an OpenVPN-Access-Gateway, ISC-DHCP-Server, Apache2, PHP5 and MySQL running. All have very little to do, but however the various network usage might have interfered with HA Bridge and the comlicated UPNP system?
@heikoh81 glad to hear it is all good
Thanks.
I also want to thank you for offering and continously developing HABridge :-)
Most helpful comment
Also, I just broke down and bought an RM Mini and a Smartplug.... That's what those donations are for :)