openhab-addons icon indicating copy to clipboard operation
openhab-addons copied to clipboard

[homeconnect] Bridge is offline after a few hours

Open TheTrueRandom opened this issue 3 years ago • 2 comments

Expected Behavior

Once the bridge is authorized (oauth) it should stay authorized (forever?).

Current Behavior

Once the bridge is authorized as described in the documentation, it is online and every device works as expected. However, it will be offline after a short time (1-3h). The openhaburl/homeconnect page shows the bridge as offline and suggests to authorize the bridge. Every time I authorize the bridge with the oauth flow, it is online and everything works for a few hours, then the bridge is offline again.

image

Possible Solution

Steps to Reproduce (for Bugs)

For me it was simply to follow the steps of the homeconnect binding documentation.

Context

Your Environment

Version used: (e.g., openHAB and add-on versions) openHAB 3.2.0 Release Build Operating System and version (desktop or mobile, Windows 10, Raspbian Buster, ...): Raspberry Pi: Linux openhabian 5.10.63-v8+

TheTrueRandom avatar Feb 21 '22 21:02 TheTrueRandom

I cannot say that it never happened but in my case it certainly does not happen systematically after a few hours. My bridge is now ONLINE since several days.

lolodomo avatar Feb 26 '22 08:02 lolodomo

I assume this problem is solved?

lolodomo avatar Aug 15 '22 14:08 lolodomo

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

stale[bot] avatar Dec 29 '22 21:12 stale[bot]

Hello, same here. homeconnect Brigdge goes to offline once a day.

Quautiputzli avatar Jan 22 '23 11:01 Quautiputzli

Hello, same here. homeconnect Brigdge goes to offline once a day.

What OH version?

Really every day?

Strange that only a part of users encounter that problem.

lolodomo avatar Jan 22 '23 12:01 lolodomo

I have the same issue. After a short time period the brigde is offline; often before the washing program is finished. After a few days at the latest.

OH 3.3.0

I use config files, not the UI.

Bridge homeconnect:api_bridge:homeconnect "Home Connect API" @ "EG" [ clientId="xxx", clientSecret="yyy", simulator=false] {
    // Thing configurations
    Thing washer EG_WK_Waschmaschine  "Waschmaschine "   @ "EG" [ haId="SIEMENS-XXX" ]
}

JHCD avatar Jan 25 '23 13:01 JHCD

I experience similar disconnects, only happening once or twice every month.

I've started collecting logs, will report back when bridge goes offline next time.

seime avatar Feb 12 '23 12:02 seime

2023-05-08 15:30:46.699 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:30:47.120 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:30:54.005 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:31:41.699 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:31:42.120 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:31:49.005 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:32:36.699 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:32:37.121 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:32:44.005 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:33:31.698 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:33:32.121 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:33:39.006 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:34:26.698 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:34:27.121 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:34:34.005 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:34:58.866 [TRACE] [OH-thingHandler-3922] [lient.HomeConnectEventSourceListener] - Check event source connection (MMMMMMMMMMMMMMMMM). Last event package received at 2023-05-08T15:34:34.005111727.
2023-05-08 15:35:06.402 [TRACE] [OH-thingHandler-3895] [lient.HomeConnectEventSourceListener] - Check event source connection (SIEMENS-HR876GDB6S-XXXXXXXXXX). Last event package received at 2023-05-08T15:34:26.698297013.
2023-05-08 15:35:06.637 [TRACE] [OH-thingHandler-3865] [lient.HomeConnectEventSourceListener] - Check event source connection (SIEMENS-CM876GDB6S-YYYYYYYYYYYY). Last event package received at 2023-05-08T15:34:27.121339905.
2023-05-08 15:35:21.698 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-HR876GDB6S-XXXXXXXXXX): Event{haId='SIEMENS-HR876GDB6S-XXXXXXXXXX', type=KEEP_ALIVE}
2023-05-08 15:35:22.123 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Received event (SIEMENS-CM876GDB6S-YYYYYYYYYYYY): Event{haId='SIEMENS-CM876GDB6S-YYYYYYYYYYYY', type=KEEP_ALIVE}
2023-05-08 15:35:24.538 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Event source listener channel closed (SIEMENS-HR876GDB6S-XXXXXXXXXX).
2023-05-08 15:35:24.539 [DEBUG] [pool-82-thread-1] [.client.HomeConnectEventSourceClient] - Closing event source. open=true, completed=true, immediate=false
2023-05-08 15:35:24.539 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Dispose event source connection monitor of appliance (SIEMENS-HR876GDB6S-XXXXXXXXXX).
2023-05-08 15:35:24.539 [DEBUG] [pool-82-thread-1] [meconnect.internal.client.HttpHelper] - Current access token: ZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZ
2023-05-08 15:35:29.006 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Received event (MMMMMMMMMMMMMMMMM): Event{haId='MMMMMMMMMMMMMMMMM', type=KEEP_ALIVE}
2023-05-08 15:35:29.756 [DEBUG] [pool-82-thread-1] [internal.client.HomeConnectApiClient] - Current access token is invalid.
2023-05-08 15:35:29.756 [DEBUG] [pool-82-thread-1] [internal.client.HomeConnectApiClient] - [SIEMENS-HR876GDB6S-XXXXXXXXXX] GET 401 https://api.home-connect.com/api/homeappliances/SIEMENS-HR876GDB6S-XXXXXXXXXX
> Authorization: Bearer ZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZ
> Accept: application/vnd.bsh.sdk.v1+json
> User-Agent: Jetty/9.4.46.v20220331
> Host: api.home-connect.com
> Accept-Encoding: gzip

< access-control-allow-headers: Authorization,Content-Type,Accept,Cache-Control,Last-Event-ID,If-Modified-Since,X-Requested-With,X-Events-Facade,User-Agent
< x-frame-options: sameorigin
< WWW-Authenticate: Bearer realm="", error="invalid_token",error_description="The access token is malformed"
< x-vcap-request-id: 9fa98723-8c34-4668-71f6-a53889d68089
< Connection: keep-alive
< Date: Mon, 08 May 2023 13:35:29 GMT
< access-control-allow-methods: GET,POST,OPTIONS,PUT,DELETE
< access-control-allow-origin: *
< access-control-allow-credentials: true
< x-content-type-options: nosniff
< x-xss-protection: 1; mode=block
< api-gateway-service: api-gateway
< referrer-policy: origin
< hc-env: EU-PRD
< Content-Length: 99
< Content-Type: application/vnd.bsh.sdk.v1+json
{
  "error": {
    "description": "The access token is malformed",
    "key": "invalid_token"
  }
}

2023-05-08 15:35:29.757 [DEBUG] [pool-82-thread-1] [dler.AbstractHomeConnectThingHandler] - Update status to OFFLINE. Home Connect service is not reachable or a problem occurred!  thing=Dampovn, haId=SIEMENS-HR876GDB6S-XXXXXXXXXX, error=Token invalid!
2023-05-08 15:35:29.757 [DEBUG] [pool-82-thread-1] [dler.AbstractHomeConnectThingHandler] - Thing handler threw authentication exception --> clear credential storage thing=Dampovn, haId=SIEMENS-HR876GDB6S-XXXXXXXXXX error=Token invalid!
2023-05-08 15:35:29.759 [DEBUG] [pool-82-thread-1] [nal.handler.HomeConnectBridgeHandler] - Reinitialize bridge Home Connect API Bridge
2023-05-08 15:35:29.761 [DEBUG] [pool-82-thread-1] [.client.HomeConnectEventSourceClient] - Closing event source. open=true, completed=false, immediate=false
2023-05-08 15:35:34.763 [DEBUG] [pool-82-thread-1] [.client.HomeConnectEventSourceClient] - Event source closed.
2023-05-08 15:35:39.765 [DEBUG] [pool-82-thread-1] [lient.HomeConnectEventSourceListener] - Dispose event source connection monitor of appliance (MMMMMMMMMMMMMMMMM).
2023-05-08 15:35:39.766 [DEBUG] [pool-82-thread-1] [nal.handler.HomeConnectBridgeHandler] - Initialize oAuth client service. tokenUrl=https://api.home-connect.com/security/oauth/token, authorizeUrl=https:
//api.home-connect.com/security/oauth/authorize, oAuthServiceHandleId=homeconnect:api_bridge:openhab, scope=IdentifyAppliance Monitor Settings Dishwasher-Control Washer-Control Dryer-Control WasherDryer-C
ontrol CoffeeMaker-Control Hood-Control Oven-Control CleaningRobot-Control, oAuthClientService=org.openhab.core.auth.oauth2client.internal.OAuthClientServiceImpl@963ce17
2023-05-08 15:35:39.770 [DEBUG] [OH-thingManager-21084] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to UNKNOWN (Oppvaskmaskin). haId=MMMMMMMMMMMMMMMMM
2023-05-08 15:35:39.770 [DEBUG] [OH-thingManager-21084] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Oppvaskmaskin). haId=MMMMMMMMMMMMMMMMM
2023-05-08 15:35:39.771 [DEBUG] [OH-thingManager-21080] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to UNKNOWN (Mikrobølgeovn). haId=SIEMENS-CM876GDB6S-YYYYYYYYYYY
2023-05-08 15:35:39.772 [DEBUG] [OH-thingManager-21080] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Mikrobølgeovn). haId=SIEMENS-CM876GDB6S-YYYYYYYYYYY
2023-05-08 15:35:39.772 [DEBUG] [OH-thingManager-21083] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to UNKNOWN (Dampovn). haId=SIEMENS-HR876GDB6S-XXXXXXXXXX
2023-05-08 15:35:39.772 [DEBUG] [OH-thingManager-21083] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Dampovn). haId=SIEMENS-HR876GDB6S-XXXXXXXXXX
2023-05-08 15:35:39.772 [DEBUG] [OH-thingManager-21080] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to OFFLINE (CONFIGURATION_PENDING): Please authenticate your account at http(s)://[YO
UROPENHAB]:[YOURPORT]/homeconnect (e.g. http://192.168.178.100:8080/homeconnect). (Oppvaskmaskin). haId=MMMMMMMMMMMMMMMMM
2023-05-08 15:35:39.773 [DEBUG] [OH-thingManager-21085] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to OFFLINE (CONFIGURATION_PENDING): Please authenticate your account at http(s)://[YO
UROPENHAB]:[YOURPORT]/homeconnect (e.g. http://192.168.178.100:8080/homeconnect). (Mikrobølgeovn). haId=SIEMENS-CM876GDB6S-YYYYYYYYYYY
2023-05-08 15:35:39.773 [DEBUG] [OH-thingManager-21083] [dler.AbstractHomeConnectThingHandler] - Bridge status changed to OFFLINE (CONFIGURATION_PENDING): Please authenticate your account at http(s)://[YO
UROPENHAB]:[YOURPORT]/homeconnect (e.g. http://192.168.178.100:8080/homeconnect). (Dampovn). haId=SIEMENS-HR876GDB6S-XXXXXXXXXX
2023-05-08 15:35:39.773 [DEBUG] [OH-thingManager-21085] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Mikrobølgeovn). haId=SIEMENS-CM876GDB6S-YYYYYYYYYYY
2023-05-08 15:35:39.773 [DEBUG] [OH-thingManager-21083] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Dampovn). haId=SIEMENS-HR876GDB6S-XXXXXXXXXX
2023-05-08 15:35:39.773 [DEBUG] [OH-thingManager-21080] [dler.AbstractHomeConnectThingHandler] - Reinitialize thing handler (Oppvaskmaskin). haId=MMMMMMMMMMMMMMMMM
2023-05-08 15:36:17.123 [DEBUG] [pool-84-thread-1] [lient.HomeConnectEventSourceListener] - Event source listener channel closed (SIEMENS-CM876GDB6S-YYYYYYYYYYY).
2023-05-08 15:36:17.123 [DEBUG] [pool-84-thread-1] [dler.AbstractHomeConnectThingHandler] - Ignoring event source close event. thing=Mikrobølgeovn, haId=SIEMENS-CM876GDB6S-YYYYYYYYYYY
2023-05-08 15:36:24.006 [DEBUG] [pool-88-thread-1] [lient.HomeConnectEventSourceListener] - Event source listener channel closed (MMMMMMMMMMMMMMMMM).
2023-05-08 15:36:24.006 [DEBUG] [pool-88-thread-1] [dler.AbstractHomeConnectThingHandler] - Ignoring event source close event. thing=Oppvaskmaskin, haId=MMMMMMMMMMMMMMMMM
2023-05-08 15:40:42.860 [DEBUG] [OH-safeCall-4158] [nal.handler.HomeConnectBridgeHandler] - Initialize oAuth client service. tokenUrl=https://api.home-connect.com/security/oauth/token, authorizeUrl=https:
//api.home-connect.com/security/oauth/authorize, oAuthServiceHandleId=homeconnect:api_bridge:openhab, scope=IdentifyAppliance Monitor Settings Dishwasher-Control Washer-Control Dryer-Control WasherDryer-C
ontrol CoffeeMaker-Control Hood-Control Oven-Control CleaningRobot-Control, oAuthClientService=org.openhab.core.auth.oauth2client.internal.OAuthClientServiceImpl@3dd0dc2b

seime avatar May 15 '23 14:05 seime

'The access token is malformed' Weird error as it should be the same token that was send before. I'll also enable the logs, just to see if i experience the same error. First message that is different is at 2023-05-08 15:34:58.866. Is that allready closing the connection?

lsiepel avatar Aug 12 '23 08:08 lsiepel

Just to mention: Same with me... API goes offline about once a month, since I use it (a little more than one year now). I will try to collect logs as well now.

Pilot001 avatar Jan 22 '24 12:01 Pilot001

Since openHAB 4, i have not seen this issue anymore. Any of you still seeing this issue with latest openHAB?

lsiepel avatar Feb 20 '24 18:02 lsiepel

It happened to me few times recently as I remember I was forced to reauthorize.

lolodomo avatar Feb 21 '24 12:02 lolodomo

It seems to be stable now. Is it still happening for someone ?

lolodomo avatar Jun 29 '24 10:06 lolodomo

For several months without any issue. Let's close this for now.

lsiepel avatar Jun 29 '24 10:06 lsiepel