I have ecobee working correctly in 1.7.1 but when I try to get it bound to 1.8 runtime with a new api key, it gives me the normal " U S E R I N T E R A C T I O N R E Q U I R E D !!" but for the pin it says ‘null’ no matter what I do. Tried the old API key, same thing, tried logging in and out, same, tried running the 1.7.1 ecobee binding and the same.
Anyone else getting the same thing?
Do you have the proper config keys set in openhab.cfg? Are you sure you are editing the openhab.cfg file in the correct location (occasional problem when people installed to /opt/openhab but later switched to apt-get or vice versa)?
Could you turn TRACE logging on? See the wiki page or just add the following to logback.xml:
<logger name="org.openhab.binding.ecobee" level="TRACE"/>
This should dump log lines when PIN authorization is happening.
I added that line to the logback.xml. I am currently running from a windows box and am editing the openhab.cfg in the root (openhab) folder.
What should I be looking for now?
Look at the contents of the openhab.log file and copy/paste anything related to the Ecobee binding that looks potentially informative. (Make sure to X out private information like keys and tokens, any IDs or passwords, etc.)
2016-01-11 17:41:18.084 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig==[317443063492#name]
2016-01-11 17:41:18.084 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=name
2016-01-11 17:41:18.084 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '=[317443063492#name]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#lastModified]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=lastModified
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#lastModified]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#runtime.actualTemperature]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.actualTemperature
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#runtime.actualTemperature]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#runtime.actualHumidity]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.actualHumidity
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#runtime.actualHumidity]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig==[317443063492#settings.hvacMode]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=settings.hvacMode
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '=[317443063492#settings.hvacMode]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#program.currentClimateRef]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=program.currentClimateRef
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#program.currentClimateRef]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#runtime.desiredHeat]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.desiredHeat
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#runtime.desiredHeat]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#runtime.desiredCool]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.desiredCool
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[317443063492#runtime.desiredCool]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig==[316529114509#name]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=name
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '=[316529114509#name]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#lastModified]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=lastModified
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#lastModified]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#runtime.actualTemperature]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.actualTemperature
2016-01-11 17:41:18.100 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#runtime.actualTemperature]'
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#runtime.actualHumidity]
2016-01-11 17:41:18.100 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.actualHumidity
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#runtime.actualHumidity]'
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig==[316529114509#settings.hvacMode]
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=settings.hvacMode
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '=[316529114509#settings.hvacMode]'
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#program.currentClimateRef]
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=program.currentClimateRef
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#program.currentClimateRef]'
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#runtime.desiredHeat]
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.desiredHeat
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#runtime.desiredHeat]'
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[316529114509#runtime.desiredCool]
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=runtime.desiredCool
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[316529114509#runtime.desiredCool]'
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with bindingConfig=<[317443063492#weather.forecasts[0].temperature]
2016-01-11 17:41:18.116 [TRACE] [i.EcobeeGenericBindingProvider] - validateItemType called with property=weather.forecasts[0].temperature
2016-01-11 17:41:18.116 [DEBUG] [i.EcobeeGenericBindingProvider] - Processing binding configuration: '<[31744306349
2016-01-11 17:41:18.272 [TRACE] [.ecobee.internal.EcobeeBinding] - Request: AuthorizeRequest[appKey=appkey=xxxxxxxxxxxxxxxxxxxxxxxxx,scope=smartWrite]
2016-01-11 17:41:18.272 [TRACE] [.ecobee.internal.EcobeeBinding] - Request: AuthorizeRequest[appKey=appkey=0g4o9AKEy7Tj4RFaNlxU9rlE5bOYQwhA,scope=smartWrite]
[INFO ] [.ecobee.internal.EcobeeBinding] - #########################################################################################
2016-01-11 17:41:18.709 [INFO ] [.ecobee.internal.EcobeeBinding] - # Ecobee-Integration: U S E R I N T E R A C T I O N R E Q U I R E D !!
2016-01-11 17:41:18.709 [INFO ] [.ecobee.internal.EcobeeBinding] - # 1. Login to www.ecobee.com using your ‘DEFAULT_USER’ account
2016-01-11 17:41:18.709 [INFO ] [.ecobee.internal.EcobeeBinding] - # 2. Enter the PIN ‘null’ in My Apps within the next null seconds.
2016-01-11 17:41:18.709 [INFO ] [.ecobee.internal.EcobeeBinding] - # NOTE: Any API attempts will fail in the meantime.
2016-01-11 17:41:18.709 [INFO ] [.ecobee.internal.EcobeeBinding] - #########################################################################################
The part you replaced with ##### would be interesting, the letters you can expose.
When you set up your app at ecobee.com and get keys, you are setting up the PIN authorization, not a redirect URL, correct?
Doh…, I guess I need either more sleep or more coffee. I had a misprint in it, when my eyes were fresh I saw it right away. It is now working. Thanks for your help and sorry for wasting your time.
No worries at all! You prompted me to update the wiki with the above screen grab, just in case it was related. Glad you got it working!
