Chamberlain MyQ Binding

Hmm, thats odd, there should not be any logic change outside of the auth process, if you restart the account thing, does the child door thing come online? If you can, turning on debug logging in the CLI like log:set DEBUG org.openhab.binding.myq and watch the log for messages as you restart the account thing.

Unfortunately you can not run a 3.x binding in 2.x, the backend core libraries are not compatible, and as i mentioned earlier, the 2.x build environment is not easily available AND this binding was a complete rewrite from the previous version, so there’s no back-porting even if we could build 2.x bindings.

In other news, the new changes has been merged, so it’s available in the nightly build. I made a few other stability changes to it since i last posted a jar , so i would recommend using that instead.

1 Like

Hey thanks for the response. Not sure what you meant by restarting the Account thing but i just deleted them and re-added them and got the same result. here are the logs from when i removed them, restarted the binding, and then added them back.

EDIT: See my post directly below this one

2021-09-11 22:11:15.502 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:6bfdf9c223' changed from UNKNOWN to REMOVING

2021-09-11 22:11:15.503 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:6bfdf9c223' changed from REMOVING to REMOVED

2021-09-11 22:11:15.506 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:6bfdf9c223' changed from REMOVED to UNINITIALIZED

2021-09-11 22:11:15.510 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:6bfdf9c223' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2021-09-11 22:11:30.032 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:5b6a6d1cde' changed from ONLINE to REMOVING

2021-09-11 22:11:30.033 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:5b6a6d1cde' changed from REMOVING to REMOVED

2021-09-11 22:11:30.038 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:5b6a6d1cde' changed from REMOVED to UNINITIALIZED

2021-09-11 22:11:30.038 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:5b6a6d1cde' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2021-09-11 22:12:12.510 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from UNINITIALIZED to INITIALIZING

2021-09-11 22:12:12.511 [DEBUG] [q.internal.handler.MyQAccountHandler] - Sending GET to https://partner-identity.myq-cloud.com/connect/authorize?
client_id=IOS_CGI_MYQ&code_challenge=SSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk&code_challenge_method=S256&redirect_uri=com.myqops%3A%2F%2Fios&response_type=code&scope=MyQ_Residential+offline_access

2021-09-11 22:12:12.512 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from INITIALIZING to UNKNOWN

2021-09-11 22:12:12.891 [DEBUG] [q.internal.handler.MyQAccountHandler] - Login Code 200 Response <!DOCTYPE html>

    <title>MyQ Security</title>

<form action="/api/Account/LoginWithEmail?returnUrl=%2Fconnect%2Fauthorize%2Fcallback%3Fclient_id%3DIOS_CGI_MYQ%26code_challenge%3DSSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk%26code_challenge_method%3DS256%26redirect_uri%3Dcom.myqops%253A%252F%252Fios%26response_type%3Dcode%26scope%3DMyQ_Residential%2520offline_access" method="post"><input id="ReturnUrl" name="ReturnUrl" type="hidden" value="/connect/authorize/callback?client_id=IOS_CGI_MYQ&amp;code_challenge=SSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk&amp;code_challenge_method=S256&amp;redirect_uri=com.myqops%3A%2F%2Fios&amp;response_type=code&amp;scope=MyQ_Residential%20offline_access" />            <div class="form-fields">

                    <a href="/api/Account/ForgotPassword?returnUrl=%2Fconnect%2Fauthorize%2Fcallback%3Fclient_id%3DIOS_CGI_MYQ%26code_challenge%3DSSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk%26code_challenge_method%3DS256%26redirect_uri%3Dcom.myqops%253A%252F%252Fios%26response_type%3Dcode%26scope%3DMyQ_Residential%2520offline_access" class="link">

2021-09-11 22:12:12.944 [DEBUG] [q.internal.handler.MyQAccountHandler] - Posting Login to https://partner-identity.myq-cloud.com/api/Account/LoginWithEmail?returnUrl=%2Fconnect%2Fauthorize%2Fcallback%3Fclient_id%3DIOS_CGI_MYQ%26code_challenge%3DSSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk%26code_challenge_method%3DS256%26redirect_uri%3Dcom.myqops%253A%252F%252Fios%26response_type%3Dcode%26scope%3DMyQ_Residential%2520offline_access

2021-09-11 22:12:12.989 [DEBUG] [q.internal.handler.MyQAccountHandler] - Redirect Login: Code 302 Response 

2021-09-11 22:12:12.989 [DEBUG] [q.internal.handler.MyQAccountHandler] - location string /connect/authorize/callback?client_id=IOS_CGI_MYQ&code_challenge=SSvQ1gje4Odo5Rr-Woj8z9wMm8Z4jWI-ZCu6Ui_8dAk&code_challenge_method=S256&redirect_uri=com.myqops%3A%2F%2Fios&response_type=code&scope=MyQ_Residential%20offline_access

2021-09-11 22:12:13.037 [DEBUG] [q.internal.handler.MyQAccountHandler] - Redirect Login: Code 302 Response 

2021-09-11 22:12:13.038 [DEBUG] [q.internal.handler.MyQAccountHandler] - location string com.myqops://ios?code=9566C1E37CF82AB28C3A8B813A26D6FEE3303953F1A6E76AD5BD96FCD711C3FF&scope=MyQ_Residential%20offline_access&iss=https%3A%2F%2Fpartner-identity.myq-cloud.com

2021-09-11 22:12:13.038 [DEBUG] [q.internal.handler.MyQAccountHandler] - Login Response URI com.myqops://ios?code=9566C1E37CF82AB28C3A8B813A26D6FEE3303953F1A6E76AD5BD96FCD711C3FF&scope=MyQ_Residential%20offline_access&iss=https%3A%2F%2Fpartner-identity.myq-cloud.com

2021-09-11 22:12:13.095 [DEBUG] [q.internal.handler.MyQAccountHandler] - Login Code 200 Response {"access_token":"","expires_in":3600,"token_type":"Bearer","refresh_token":"","scope":"MyQ_Residential offline_access"}

2021-09-11 22:12:13.096 [DEBUG] [q.internal.handler.MyQAccountHandler] - Login Token response {"access_token":"","expires_in":3600,"token_type":"Bearer","refresh_token":"","scope":"MyQ_Residential offline_access"}

2021-09-11 22:12:13.339 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from UNKNOWN to ONLINE

2021-09-11 22:13:24.467 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from UNINITIALIZED to INITIALIZING

2021-09-11 22:13:24.469 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from INITIALIZING to UNKNOWN

2021-09-11 22:17:49.405 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Querying state active

2021-09-11 22:17:49.405 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Deactivating component

2021-09-11 22:17:49.406 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : registration change queue [unregistered]

2021-09-11 22:17:49.408 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from UNKNOWN to UNINITIALIZED

2021-09-11 22:17:49.412 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2021-09-11 22:17:49.414 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from ONLINE to UNINITIALIZED

2021-09-11 22:17:49.417 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)

2021-09-11 22:17:49.417 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Querying state active

2021-09-11 22:17:49.418 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : getting deactivate: deactivate

2021-09-11 22:17:49.418 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Locating method deactivate in class org.openhab.binding.myq.internal.MyQHandlerFactory

2021-09-11 22:17:49.418 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Declared Method org.openhab.binding.myq.internal.MyQHandlerFactory.deactivate([interface org.osgi.service.component.ComponentContext]) not found

2021-09-11 22:17:49.419 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Locating method deactivate in class org.openhab.core.thing.binding.BaseThingHandlerFactory

2021-09-11 22:17:49.419 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Found deactivate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.deactivate(org.osgi.service.component.ComponentContext)

2021-09-11 22:17:49.419 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : invoking deactivate: deactivate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]

2021-09-11 22:17:49.420 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : invoked deactivate: deactivate

2021-09-11 22:17:49.421 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : DependencyManager: $001 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@27d3d7dd at tracking count 2 refpairs: [[RefPair: ref: [{org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=338, service.bundleid=157, service.scope=bundle, component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=51}] service: [org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl@5ed65388]]]

2021-09-11 22:17:49.421 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : DependencyManager: $000 close component unbinding from org.apache.felix.scr.impl.manager.ComponentContextImpl@27d3d7dd at tracking count 1 refpairs: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=267, service.bundleid=177, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=135}] service: [org.openhab.core.io.net.http.internal.WebClientFactoryImpl@431420dd]]]

2021-09-11 22:17:49.421 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Unset and deconfigured implementation object for component in deleteComponent for reason Unspecified

2021-09-11 22:17:49.422 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Changed state from active to satisfied

2021-09-11 22:17:49.429 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Deactivating dependency managers

2021-09-11 22:17:49.432 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Disabling dependency managers

2021-09-11 22:17:49.437 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : unregistering service listener for dependency $000

2021-09-11 22:17:49.438 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : unregistering service listener for dependency $001

2021-09-11 22:17:49.438 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Querying state satisfied

2021-09-11 22:17:49.439 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Changed state from satisfied to disposed

2021-09-11 22:17:49.439 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(322)] : Disposing component (reason: 6)

2021-09-11 22:17:49.450 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : BundleComponentActivator : ComponentHolder created.

2021-09-11 22:17:49.451 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.io.net.http.HttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0

2021-09-11 22:17:49.451 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Dependency Manager created $001interface=org.openhab.core.auth.client.oauth2.OAuthFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=1

2021-09-11 22:17:49.451 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.myq.internal.MyQHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.myq]

2021-09-11 22:17:49.451 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]

2021-09-11 22:17:49.451 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Component Properties: {}

2021-09-11 22:17:49.452 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Querying state disabled

2021-09-11 22:17:49.452 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Querying state disabled

2021-09-11 22:17:49.452 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Component can not be activated since it is in state disabled

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory] : Querying state disabled

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Updating target filters

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : No change in target property for dependency $000: currently registered: false

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] :  No existing service listener to unregister for dependency $000

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Setting target property for dependency $000 to null

2021-09-11 22:17:49.453 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)

2021-09-11 22:17:49.454 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $000 tracker reset (closed)

2021-09-11 22:17:49.454 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=267, service.bundleid=177, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=135} (enter)

2021-09-11 22:17:49.454 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false

2021-09-11 22:17:49.455 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=267, service.bundleid=177, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=135} (exit)

2021-09-11 22:17:49.455 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $000 tracker opened

2021-09-11 22:17:49.455 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : registering service listener for dependency $000

2021-09-11 22:17:49.455 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : No change in target property for dependency $001: currently registered: false

2021-09-11 22:17:49.455 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] :  No existing service listener to unregister for dependency $001

2021-09-11 22:17:49.456 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Setting target property for dependency $001 to null

2021-09-11 22:17:49.456 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory), initialReferenceFilter (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory)

2021-09-11 22:17:49.456 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $001 tracker reset (closed)

2021-09-11 22:17:49.457 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=338, service.bundleid=157, service.scope=bundle, component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=51} (enter)

2021-09-11 22:17:49.457 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false

2021-09-11 22:17:49.457 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=338, service.bundleid=157, service.scope=bundle, component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=51} (exit)

2021-09-11 22:17:49.457 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : dm $001 tracker opened

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : registering service listener for dependency $001

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Changed state from disabled to unsatisfiedReference

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Component enabled

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : ActivateInternal

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state unsatisfiedReference

2021-09-11 22:17:49.458 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state unsatisfiedReference

2021-09-11 22:17:49.459 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Activating component from state unsatisfiedReference

2021-09-11 22:17:49.459 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state unsatisfiedReference

2021-09-11 22:17:49.459 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state unsatisfiedReference

2021-09-11 22:17:49.459 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Changed state from unsatisfiedReference to satisfied

2021-09-11 22:17:49.459 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : registration change queue [registered]

2021-09-11 22:17:49.460 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Checking constructor public org.openhab.binding.myq.internal.MyQHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.auth.client.oauth2.OAuthFactory)

2021-09-11 22:17:49.460 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getReferenceClass: Looking for interface class org.openhab.core.io.net.http.HttpClientFactory through loader of org.openhab.binding.myq.internal.MyQHandlerFactory

2021-09-11 22:17:49.460 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getParameterClass: Found class org.openhab.core.io.net.http.HttpClientFactory

2021-09-11 22:17:49.460 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getReferenceClass: Looking for interface class org.openhab.core.auth.client.oauth2.OAuthFactory through loader of org.openhab.binding.myq.internal.MyQHandlerFactory

2021-09-11 22:17:49.461 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getParameterClass: Found class org.openhab.core.auth.client.oauth2.OAuthFactory

2021-09-11 22:17:49.461 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Found constructor with 2 arguments : public org.openhab.binding.myq.internal.MyQHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.auth.client.oauth2.OAuthFactory)

2021-09-11 22:17:49.461 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : This thread collected dependencies

2021-09-11 22:17:49.461 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getService (single component manager) dependencies collected.

2021-09-11 22:17:49.461 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state satisfied

2021-09-11 22:17:49.462 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Querying state satisfied

2021-09-11 22:17:49.462 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=267, service.bundleid=177, service.scope=bundle, component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=135}] service: [null]]]

2021-09-11 22:17:49.462 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=338, service.bundleid=157, service.scope=bundle, component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=51}] service: [null]]]

2021-09-11 22:17:49.462 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : getting activate: activate

2021-09-11 22:17:49.462 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Locating method activate in class org.openhab.binding.myq.internal.MyQHandlerFactory

2021-09-11 22:17:49.463 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Declared Method org.openhab.binding.myq.internal.MyQHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found

2021-09-11 22:17:49.463 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory

2021-09-11 22:17:49.463 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)

2021-09-11 22:17:49.463 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]

2021-09-11 22:17:49.463 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : invoked activate: activate

2021-09-11 22:17:49.464 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Set implementation object for component

2021-09-11 22:17:49.464 [DEBUG] [nding.myq.internal.MyQHandlerFactory] - bundle org.openhab.binding.myq:3.2.0.202108311743 (275)[org.openhab.binding.myq.internal.MyQHandlerFactory(329)] : Changed state from satisfied to active

2021-09-11 22:17:49.471 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING

2021-09-11 22:17:49.474 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from INITIALIZING to UNKNOWN

2021-09-11 22:17:49.476 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING

2021-09-11 22:17:49.477 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:garagedoor:77ab746642:187da3b583' changed from INITIALIZING to UNKNOWN

2021-09-11 22:17:49.530 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'myq:account:77ab746642' changed from UNKNOWN to ONLINE

Just installed this binding and upon doing so, my UI is now showing me ERROR:COMM for the account thing with this message:

COMMUNICATION_ERROR
Invalid Response Code 400 : { "SecurityToken": "", "ReturnCode": "0", "ErrorMessage": "please contact customer care, supportID: 3399478270084114836", "CorrelationId": "" }

the version of the binding that produced this message is:

bundle org.openhab.binding.myq:3.2.0.202109110450 (280)

Same issue here with this latest version. The previous version that was first released for this issue worked great in my environment.

I did try dropping the latest version into the addons folder, then restart, then cache clear, then re-add jsoup via the console, then another restart with no success after any of these actions. Same error message as the above post.

Restoring the previous version into the addons folder worked fine with no further steps.

Cheers

I also cleared the cache, downgraded to the same version of the binding (org.openhab.binding.myq:3.2.0.202108311743) and re-added jsoup.

Same result:
Account: Online
Garage Door: Unknown

Just to make sure I’m not missing something obvious, I put my Garage Door opener Serial Number in the item config. It was written on the side of my unit.

Also just one more note: Regardless of whether i enter my username and password into the Account thing correctly or not, it still says Online. Not sure if that is expected behavior.

hmm, that should have been already populated, how are you configuring you garage door things, manually in an thing file or through the UI? If you delete your things (account and door), then add the account thing back first and let it auto discover the door thing, does that work?

I have seen that frequently at login, we get a 400 token error from the MyQ service, if you wait (60 seconds) the binding will try to get a new token again, its usually successful the second time (although once it needed to do it 3 times). Once we have a token, we don’t need to auth again unless the binding is restarted or the credentials change. The MyQ auth service is very touchy.

1 Like

Hey that worked!!! I wasnt letting it auto-discover but once i did, it added and is working! I will note for future users that the serial number listed in the Garage Door Item is NOT the same one as is printed on the side of my opener. Not a big deal but that was probably my issue so wanted to put it out there.

I seem to be back to the original problem with my bridge. I’m on OH 3.2.0.M2 and the binding and bridge were working, but once again my MyQ Account thing has a status of ERROR:COMM.

I’ve reinstalled the 3.2.0-SNAPSHOT and it’s active at bundle 235. The 3.2.0.M2 release bundle 273 is at status Resolved and the jsoup bundle is active.

I’ve cleared out the cache and restarted OpenHAB several times, all with no luck. Have I missed something obvious?

Hi @DaveL , are you using the version that comes with 3.2.0.M2, or did you install from the nightly build of the jar after you reinstalled openHAB ?

Hi @digitaldan, I stopped the standard M2 bundle then installed the jar file. I’d downloaded it…two days ago I believe. I can try the nightly build

How did you install, by adding it to the addons folder or by using the CLI console to install/update? It will work fine with milestone releases. I would uninstall the binding completely if you are adding it to the addons folder.

I added to the addons folder. And I just downloaded the newest build, added it to the folder and restarted OpenHAB. And it’s working again! Thanks again, @digitaldan

1 Like

Just wanted to add a note that I’m having zero problems with the new binding by updating to the OpenHAB SNAPSHOT build. don’t even need to manually install the binding or jsoup. Just used openhabian-config to switch to snapshots in the openhab-related menu, and it installed and restarted openhab and everything was happy. Seemed simpler than manually installing the individual binding, and its dependencies.

I had to install it manually since I’m not using openhabian, but it is working flawlessly now.

I finally got the binding to connect, but I am not seeing any auto-discovered things.

Does that need to be kicked off? I have two garage door openers, but don’t see how to add them.

It should do that automatically, but you can manually kick it off in the UI by going to “Settings” → “Things” and then click on the “+” symbol at the bottom of the screen:

Select the “MyQ” binding from there, and then press “Scan”:

It should find your devices, which will also now be in the “Inbox”.

That was it I had to go and force the scan by binding. It discovered the new door.

I did still need to go into the admin console and delete the old garage door opener to get it working again.

Thank you!

1 Like

hey man, I really appreciate you taking the time to update this binding and deal with all of us novices in learning how to use it!

1 Like

Hi I’m migrating from OH2.4 to OH3 because the old binding is no longer supported and can’t be rebuilt.

I’m on OH3.2 Snapshot and tried installing the Snapshot binding and the binding from

I’ve installed JSoup
Clean-cache between installs.

The Account initially shows online then switches to offline with Comm Error.
What’s the correct set of steps to get this to connect?