Ha-bridge: Something changed for Echo with 2.0.0?

Created on 27 Apr 2016  路  41Comments  路  Source: bwssytems/ha-bridge

I had 1.4.3 running fine talking to an API of my own. It returns 200 with PHP headers. It works fine on 1.4.3. I downloaded the 2.0.0 jar and even though I didn't change anything Echo says 'the device didn't respond' but it does switch. The command I see in the log of my API is the same. Do I need to send something else back?

bug question
Source
picture osenbruggen

All 41 comments

Are there any errors in the ha-bridge log? Maybe the echo timed out as the call took too long?

bwssytems picture bwssytems on 28 Apr 2016

I'm getting the same. The switch happens almost instantly, the log shows it got a 200 response from my API but Alexa says it did not respond.

fearoffish picture fearoffish on 28 Apr 2016

On top of that I ran a forget and discover. All my devices are no longer seen by Alexa.

fearoffish picture fearoffish on 28 Apr 2016

When I run the test buttons everything is fine btw.

osenbruggen picture osenbruggen on 28 Apr 2016

Please turn on trace upnp and post your logs after a discover from the echo

bwssytems picture bwssytems on 28 Apr 2016

Here you go:

10.0.0.12 is the bridge
10.0.0.37 is the Echo

Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,398 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,398 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,398 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 13:18:27 ha java[9328]: HOST: 239.255.255.250:1900
Apr 28 13:18:27 ha java[9328]: MAN: "ssdp:discover"
Apr 28 13:18:27 ha java[9328]: MX: 15
Apr 28 13:18:27 ha java[9328]: ST: urn:Belkin:device:**
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,399 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,499 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,499 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,499 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 13:18:27 ha java[9328]: HOST: 239.255.255.250:1900
Apr 28 13:18:27 ha java[9328]: MAN: "ssdp:discover"
Apr 28 13:18:27 ha java[9328]: MX: 15
Apr 28 13:18:27 ha java[9328]: ST: urn:Belkin:device:**
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,500 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,606 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,607 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,607 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 13:18:27 ha java[9328]: HOST: 239.255.255.250:1900
Apr 28 13:18:27 ha java[9328]: MAN: "ssdp:discover"
Apr 28 13:18:27 ha java[9328]: MX: 15
Apr 28 13:18:27 ha java[9328]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,607 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,710 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,710 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,710 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 13:18:27 ha java[9328]: HOST: 239.255.255.250:1900
Apr 28 13:18:27 ha java[9328]: MAN: "ssdp:discover"
Apr 28 13:18:27 ha java[9328]: MX: 15
Apr 28 13:18:27 ha java[9328]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,711 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,836 [qtp5552193-33] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,837 [qtp5552193-33] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,845 [qtp5552193-37] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 13:18:27 ha java[9328]: 2016-04-28 13:18:27,846 [qtp5552193-37] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 13:18:28 ha java[9328]: 2016-04-28 13:18:28,353 [qtp5552193-68] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 13:18:28 ha java[9328]: 2016-04-28 13:18:28,353 [qtp5552193-66] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
fearoffish picture fearoffish on 28 Apr 2016

Also, please provide the IP address of your ha-bridge and the Amazon echo. It will help to see what is calling the bridge. Thanks

bwssytems picture bwssytems on 28 Apr 2016

Post updated. Thanks :)

fearoffish picture fearoffish on 28 Apr 2016

Oh and 8124 is the bridge port, 50000 is the UPNP port.

fearoffish picture fearoffish on 28 Apr 2016

I've hijacked this issue, I just realised. I'm sorry! Would you like me to start another and leave this one to the 'next' issue :)

fearoffish picture fearoffish on 28 Apr 2016

This is fine and it may be related.

bwssytems picture bwssytems on 28 Apr 2016

All the devices in Alexa appeared as Offline before I did a rediscover. @osenbruggen do you see that?

fearoffish picture fearoffish on 28 Apr 2016

Well, the log shows that the Echo did the correct things with the ha-bridge and the ha-bridge responded appropriately. Will need to look at the details of the responses to make sure there isn't something weird in there.

bwssytems picture bwssytems on 28 Apr 2016

Anything I can do to help debug, let me know.

fearoffish picture fearoffish on 28 Apr 2016

try this jar file as I think I found the issue

http://www.bwssystems.com/files/ha-bridge-2.0.0a.jar

bwssytems picture bwssytems on 28 Apr 2016

Okay, discovery now works. Except all the discovered devices appear as offline (in Alexa).

fearoffish picture fearoffish on 28 Apr 2016

Alexa is functioning correctly though (except for the error about devices not responding). So we're back on issue topic at least.

fearoffish picture fearoffish on 28 Apr 2016

New debug log for when I ask the bedroom lights to turn off:

Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,788 [qtp11091396-10] DEBUG spark.webserver.MatcherFilter - httpMethod:put, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights/1532947647/state
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,790 [qtp11091396-10] DEBUG spark.Request - get params
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,791 [qtp11091396-10] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,791 [qtp11091396-10] DEBUG spark.Request - matchedPart: :id = 1532947647
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,792 [qtp11091396-10] DEBUG spark.Request - get splat
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,794 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue state change requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37 body: {"on": false}
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,814 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - executing HUE api request for network call: https://home.fearoffish.com:8123/api/services/homeassistant/turn_off
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,829 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - executing HUE api request to Http POST: https://home.fearoffish.com:8123/api/services/homeassistant/turn_off
Apr 28 14:23:07 ha java[31041]: 2016-04-28 14:23:07,833 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - Making outbound call in doHttpRequest: POST https://home.fearoffish.com:8123/api/services/homeassistant/turn_off HTTP/1.1
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,056 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - POST execute on URL responded: 200
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,392 [qtp11091396-13] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights/1532947647
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,394 [qtp11091396-13] DEBUG spark.Request - get params
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,394 [qtp11091396-13] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,394 [qtp11091396-13] DEBUG spark.Request - matchedPart: :id = 1532947647
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,394 [qtp11091396-13] DEBUG spark.Request - get splat
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,395 [qtp11091396-13] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue light requested: 1532947647 for user: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,395 [qtp11091396-13] DEBUG com.bwssystems.HABridge.hue.HueMulator - found device named: Bedroom Lights
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,699 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,700 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Got SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,700 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,701 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,701 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,701 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,702 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,800 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,800 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Got SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,800 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,800 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,800 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:schemas-upnp-org:device:basic:1
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,801 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,801 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,907 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,907 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Got SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:Belkin:device:**
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,907 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,907 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,908 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:08 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:08 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:08 ha java[31041]: MX: 3
Apr 28 14:23:08 ha java[31041]: ST: urn:Belkin:device:**
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,908 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:08 ha java[31041]: 2016-04-28 14:23:08,909 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,008 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Found message to be an M-SEARCH message.
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,008 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery Got SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:09 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:09 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:09 ha java[31041]: MX: 3
Apr 28 14:23:09 ha java[31041]: ST: urn:Belkin:device:**
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,008 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be an M-SEARCH message.
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,008 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,009 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: SSDP packet from 10.0.0.37:50000, body: M-SEARCH * HTTP/1.1
Apr 28 14:23:09 ha java[31041]: HOST: 239.255.255.250:1900
Apr 28 14:23:09 ha java[31041]: MAN: "ssdp:discover"
Apr 28 14:23:09 ha java[31041]: MX: 3
Apr 28 14:23:09 ha java[31041]: ST: urn:Belkin:device:**
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,009 [main] DEBUG com.bwssystems.HABridge.upnp.UpnpListener - isSSDPDiscovery found message to be valid under loose rules - strict: false
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,009 [main] INFO  com.bwssystems.HABridge.upnp.UpnpListener - Traceupnp: sendUpnpResponse discovery template with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,153 [qtp11091396-14] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /description.xml
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,154 [qtp11091396-14] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,155 [qtp11091396-14] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,156 [qtp11091396-14] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,157 [qtp11091396-14] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,221 [qtp11091396-10] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /description.xml
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,223 [qtp11091396-10] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,223 [qtp11091396-14] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /description.xml
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,224 [qtp11091396-10] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,224 [qtp11091396-13] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /description.xml
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,225 [qtp11091396-10] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,226 [qtp11091396-14] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,227 [qtp11091396-13] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,228 [qtp11091396-10] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,228 [qtp11091396-14] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,230 [qtp11091396-13] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,235 [qtp11091396-14] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,237 [qtp11091396-13] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings requested: null from 10.0.0.37:8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,239 [qtp11091396-14] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,242 [qtp11091396-13] INFO  com.bwssystems.HABridge.upnp.UpnpSettingsResource - Traceupnp: upnp device settings template filled with address: 10.0.0.12 and port: 8124
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,949 [qtp11091396-16] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,951 [qtp11091396-15] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,952 [qtp11091396-16] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,952 [qtp11091396-16] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,952 [qtp11091396-15] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,952 [qtp11091396-16] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,953 [qtp11091396-15] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,953 [qtp11091396-16] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,953 [qtp11091396-15] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,954 [qtp11091396-16] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,954 [qtp11091396-15] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,955 [qtp11091396-11] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,955 [qtp11091396-15] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,956 [qtp11091396-11] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,956 [qtp11091396-11] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,957 [qtp11091396-11] DEBUG spark.Request - get splat
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,957 [qtp11091396-11] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,957 [qtp11091396-11] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,959 [qtp11091396-10] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,961 [qtp11091396-10] DEBUG spark.Request - get params
Apr 28 14:23:09 ha java[31041]: 2016-04-28 14:23:09,962 [qtp11091396-10] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:10 ha java[31041]: 2016-04-28 14:23:09,964 [qtp11091396-10] DEBUG spark.Request - get splat
Apr 28 14:23:10 ha java[31041]: 2016-04-28 14:23:09,969 [qtp11091396-10] INFO  com.bwssystems.HABridge.hue.HueMulator - Traceupnp: hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:10 ha java[31041]: 2016-04-28 14:23:09,971 [qtp11091396-10] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue lights list requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,620 [qtp11091396-14] DEBUG spark.webserver.MatcherFilter - httpMethod:put, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights/1532947647/state
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,621 [qtp11091396-14] DEBUG spark.Request - get params
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,622 [qtp11091396-14] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,622 [qtp11091396-14] DEBUG spark.Request - matchedPart: :id = 1532947647
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,622 [qtp11091396-14] DEBUG spark.Request - get splat
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,622 [qtp11091396-14] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue state change requested: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37 body: {"on": false}
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,625 [qtp11091396-14] DEBUG com.bwssystems.HABridge.hue.HueMulator - executing HUE api request for network call: https://home.fearoffish.com:8123/api/services/homeassistant/turn_off
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,626 [qtp11091396-14] DEBUG com.bwssystems.HABridge.hue.HueMulator - executing HUE api request to Http POST: https://home.fearoffish.com:8123/api/services/homeassistant/turn_off
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,626 [qtp11091396-14] DEBUG com.bwssystems.HABridge.hue.HueMulator - Making outbound call in doHttpRequest: POST https://home.fearoffish.com:8123/api/services/homeassistant/turn_off HTTP/1.1
Apr 28 14:23:11 ha java[31041]: 2016-04-28 14:23:11,798 [qtp11091396-14] DEBUG com.bwssystems.HABridge.hue.HueMulator - POST execute on URL responded: 200
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,135 [qtp11091396-13] DEBUG spark.webserver.MatcherFilter - httpMethod:get, uri: /api/hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29/lights/1532947647
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,136 [qtp11091396-13] DEBUG spark.Request - get params
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,137 [qtp11091396-13] DEBUG spark.Request - matchedPart: :userid = hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,137 [qtp11091396-13] DEBUG spark.Request - matchedPart: :id = 1532947647
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,137 [qtp11091396-13] DEBUG spark.Request - get splat
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,137 [qtp11091396-13] DEBUG com.bwssystems.HABridge.hue.HueMulator - hue light requested: 1532947647 for user: hm3JnxYjJAKVDx8OkGQZwk1UKTwslVGKn0or5N29 from 10.0.0.37
Apr 28 14:23:12 ha java[31041]: 2016-04-28 14:23:12,137 [qtp11091396-13] DEBUG com.bwssystems.HABridge.hue.HueMulator - found device named: Bedroom Lights
fearoffish picture fearoffish on 28 Apr 2016

I didn't try rediscover, since I assumed there was a problem I switched back. Should that .a jar also fix the response ?

osenbruggen picture osenbruggen on 28 Apr 2016

It fixes the discover, but I don't think he's worked on the 'Offline' bug yet. Do your devices appear as Offline in the Alexa app though, @osenbruggen?

fearoffish picture fearoffish on 28 Apr 2016

More updates that should be good for both issues, try this jar file:

http://www.bwssystems.com/files/ha-bridge-2.0.0b.jar

bwssytems picture bwssytems on 28 Apr 2016

Yep, that works perfectly. Swift help and bug fixing. Keep up the good work, and thank you!

fearoffish picture fearoffish on 28 Apr 2016

I will test later tonight. Thanks.

osenbruggen picture osenbruggen on 28 Apr 2016

It looks like I dropped off the state object resposne for a device when I implemented the Hue proxy feature. Thanks

bwssytems picture bwssytems on 28 Apr 2016

@fearoffish - I see you are taking advantage of the HTTPS call. Great!

bwssytems picture bwssytems on 28 Apr 2016

@bwssytems Yeah. I finally had to bite the bullet and buy an SSL cert for my domain too, instead of using letsencrypt. I had to manually add my letsencrypt cert to the java keystore every time before...now it just works. :)

So, ha-bridge with home-assistant is a really good pair now :) I just use the custom type and send https api calls :)

fearoffish picture fearoffish on 28 Apr 2016

That is Awesome! Gluing the world together bit by bit...

bwssytems picture bwssytems on 28 Apr 2016
❤1

Oh and thanks for the custom headers addition, I needed that for home-assistant auth to work.

fearoffish picture fearoffish on 28 Apr 2016

Do you happen to have a write up on how to do this with Home Assistant? IT would be great to get that out there.

bwssytems picture bwssytems on 28 Apr 2016

I don't, but I could definitely put one together. I'll do that at the weekend (hopefully).

fearoffish picture fearoffish on 28 Apr 2016
👍1

@bwssytems How's this? http://fear-of-fish.ghost.io/home-assistant-and-ha-bridge/

fearoffish picture fearoffish on 28 Apr 2016

That is great! If you don't mind, I'll just link to that in the Bridge section of the bwssystems.com website.

bwssytems picture bwssytems on 28 Apr 2016

Feel free. I'll be adding more to the blog, but it won't all be HA-Bridge specific. FYI.

fearoffish picture fearoffish on 28 Apr 2016

Not a problem, any HA info is good!

bwssytems picture bwssytems on 28 Apr 2016

@fearoffish AWESOME! Thanks for taking the time to document and share. I have a blog (http://coreyswrite.com/?p=394) where I detailed connecting X10 equipment to the Echo using the ha-bridge, I'll make a post and throw a link to your site on there.

audiofreak9 picture audiofreak9 on 28 Apr 2016

Nice blog, I like the amount of detail. I burnt out my blogging many years ago, so I'm starting with short-ish posts. Yours are looooong and detailed :)

fearoffish picture fearoffish on 28 Apr 2016

Thanks to your work I can create a Home Assist -> ha-bridge connection to control my X10 with the Home Assist app.

audiofreak9 picture audiofreak9 on 28 Apr 2016

OK sorry for the delay. It works for me now as well :)

osenbruggen picture osenbruggen on 29 Apr 2016

I took the more stubborn way by the way. I wrote my own HA stuff locally. PHP / MySQL based. ha-bridge talks to this app and it on it turn talks to my Nest / Fibaro / Domoticz / MrHouse / Hue / etc... In this way I only let ha-bridge give simple HTTP API commands that I interpret and lookup the devices in the database to see what types they are and what commands they need. In this way I can interface anything I like. It also handles timed events, trigger based events, scenes, groups etc. so in this way I'm not depending on intelligence in my controllers. I have a lot of devices and had lots of communication problems in the past on Z-Wave. Now I divided everything into small Islands with its own controller and run a central brain. I also made my own Echo custom skill that also sends just HTTP to the same API. In this way I can ask my app anything I like. It is nice to have this freedom I think. Maybe other people reading this that know how to code can get inspired. So I though maybe share this idea here. Thanks for the quick fix :)

osenbruggen picture osenbruggen on 29 Apr 2016

@bwssytems Could you change the URL to my blog post, I've decided to be serious and moved it to my github pages: http://fearoffish.com/home-automation/home-assistant-and-ha-bridge/

fearoffish picture fearoffish on 12 May 2016

Done

bwssytems picture bwssytems on 13 May 2016
Was this page helpful?
0 / 5 - 0 ratings

Related issues

emiliosic picture emiliosic  路  36Comments
jayjupdhig picture jayjupdhig  路  42Comments
rodders99 picture rodders99  路  28Comments
painkillerde picture painkillerde  路  46Comments
SVLoneStar picture SVLoneStar  路  54Comments